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/05/21 18:21:09 UTC
[JENKINS-EA] Lucene-Solr-master-Linux
(64bit/jdk-13-ea+shipilev-fastdebug) - Build # 24124 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/24124/
Java: 64bit/jdk-13-ea+shipilev-fastdebug -XX:-UseCompressedOops -XX:+UseG1GC
2 tests failed.
FAILED: org.apache.solr.metrics.rrd.SolrRrdBackendFactoryTest.testBasic
Error Message:
{} expected:<1> but was:<0>
Stack Trace:
java.lang.AssertionError: {} expected:<1> but was:<0>
at __randomizedtesting.SeedInfo.seed([35CADB86504033DA:9E30C6938F9CB5F4]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:645)
at org.apache.solr.metrics.rrd.SolrRrdBackendFactoryTest.testBasic(SolrRrdBackendFactoryTest.java:94)
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)
FAILED: org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth
Error Message:
must have failed
Stack Trace:
java.lang.AssertionError: must have failed
at __randomizedtesting.SeedInfo.seed([35CADB86504033DA:89A4AD94F413B0A0]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth(BasicAuthIntegrationTest.java:204)
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 13802 lines...]
[junit4] Suite: org.apache.solr.metrics.rrd.SolrRrdBackendFactoryTest
[junit4] 2> 1333112 INFO (SUITE-SolrRrdBackendFactoryTest-seed#[35CADB86504033DA]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.metrics.rrd.SolrRrdBackendFactoryTest_35CADB86504033DA-001/init-core-data-001
[junit4] 2> 1333113 WARN (SUITE-SolrRrdBackendFactoryTest-seed#[35CADB86504033DA]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 1333113 INFO (SUITE-SolrRrdBackendFactoryTest-seed#[35CADB86504033DA]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1333114 INFO (SUITE-SolrRrdBackendFactoryTest-seed#[35CADB86504033DA]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1333117 INFO (TEST-SolrRrdBackendFactoryTest.testBasic-seed#[35CADB86504033DA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBasic
[junit4] 2> 1333203 DEBUG (TEST-SolrRrdBackendFactoryTest.testBasic-seed#[35CADB86504033DA]) [ ] o.a.s.m.r.SolrRrdBackendFactory Created 1152538172
[junit4] 2> 1333223 DEBUG (SolrRrdBackendFactory-3555-thread-1) [ ] o.a.s.m.r.SolrRrdBackendFactory -- maybe sync backends: [foo]
[junit4] 2> 1333350 DEBUG (SolrRrdBackendFactory-3555-thread-1) [ ] o.a.s.m.r.SolrRrdBackendFactory -- syncing [foo]
[junit4] 2> 1333468 DEBUG (TEST-SolrRrdBackendFactoryTest.testBasic-seed#[35CADB86504033DA]) [ ] o.a.s.m.r.SolrRrdBackendFactory Closing 1152538172
[junit4] 2> 1333468 INFO (TEST-SolrRrdBackendFactoryTest.testBasic-seed#[35CADB86504033DA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testBasic
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrRrdBackendFactoryTest -Dtests.method=testBasic -Dtests.seed=35CADB86504033DA -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=mr-IN -Dtests.timezone=Zulu -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 0.44s J0 | SolrRrdBackendFactoryTest.testBasic <<<
[junit4] > Throwable #1: java.lang.AssertionError: {} expected:<1> but was:<0>
[junit4] > at __randomizedtesting.SeedInfo.seed([35CADB86504033DA:9E30C6938F9CB5F4]:0)
[junit4] > at org.apache.solr.metrics.rrd.SolrRrdBackendFactoryTest.testBasic(SolrRrdBackendFactoryTest.java:94)
[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:830)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.metrics.rrd.SolrRrdBackendFactoryTest_35CADB86504033DA-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {}, docValues:{}, maxPointsInLeafNode=380, maxMBSortInHeap=5.8595508200240864, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@3822dd5b), locale=mr-IN, timezone=Zulu
[junit4] 2> NOTE: Linux 4.18.0-17-generic amd64/Aleksey Shipilev 13-testing (64-bit)/cpus=8,threads=1,free=118891728,total=325058560
[junit4] 2> NOTE: All tests run in this JVM: [ParsingFieldUpdateProcessorsTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, PreAnalyzedFieldManagedSchemaCloudTest, DistributedFacetPivotWhiteBoxTest, ConcurrentCreateRoutedAliasTest, TestRandomDVFaceting, ReplaceNodeTest, TestInitQParser, CursorMarkTest, TestAtomicUpdateErrorCases, SpatialHeatmapFacetsTest, TestSimpleTextCodec, TestIntervalFaceting, TestSimExtremeIndexing, RandomizedTaggerTest, SuggestComponentTest, NumberUtilsTest, TestBinaryField, TestZkChroot, UnloadDistributedZkTest, IndexSchemaTest, TestWordDelimiterFilterFactory, RootFieldTest, SimplePostToolTest, HighlighterConfigTest, TestXmlQParserPlugin, TriggerCooldownIntegrationTest, TestOverriddenPrefixQueryForCustomFieldType, AutoScalingHandlerTest, AnalysisAfterCoreReloadTest, TestTestInjection, CoreAdminHandlerTest, OverseerStatusTest, DistributedExpandComponentTest, PeerSyncWithIndexFingerprintCachingTest, CreateCollectionCleanupTest, TestQuerySenderNoQuery, HdfsNNFailoverTest, TestSolrQueryParser, PathHierarchyTokenizerFactoryTest, ReindexCollectionTest, CloudExitableDirectoryReaderTest, TestXIncludeConfig, DocValuesMissingTest, TestRebalanceLeaders, TestImpersonationWithHadoopAuth, TestSchemaSimilarityResource, CollectionPropsTest, TestDistribIDF, TestStressUserVersions, TestCloudNestedDocsSort, TestSolrConfigHandlerConcurrent, TestGroupingSearch, TestMinMaxOnMultiValuedField, SpellPossibilityIteratorTest, TestUseDocValuesAsStored, ReturnFieldsTest, TestCoreContainer, TestInPlaceUpdateWithRouteField, DistributedQueryComponentOptimizationTest, TestJettySolrRunner, TestCloudPhrasesIdentificationComponent, WordBreakSolrSpellCheckerTest, TestMacros, SolrRrdBackendFactoryTest]
[junit4] Completed [229/858 (1!)] on J0 in 1.29s, 1 test, 1 failure <<< FAILURES!
[...truncated 2015 lines...]
[junit4] Suite: org.apache.solr.security.BasicAuthIntegrationTest
[junit4] 2> 3735091 INFO (SUITE-BasicAuthIntegrationTest-seed#[35CADB86504033DA]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_35CADB86504033DA-001/init-core-data-001
[junit4] 2> 3735092 INFO (SUITE-BasicAuthIntegrationTest-seed#[35CADB86504033DA]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 3735095 INFO (SUITE-BasicAuthIntegrationTest-seed#[35CADB86504033DA]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 3735109 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBasicAuth
[junit4] 2> 3735110 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_35CADB86504033DA-001/tempDir-001
[junit4] 2> 3735111 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 3735124 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 3735124 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 3735220 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.c.ZkTestServer start zk server on port:33805
[junit4] 2> 3735220 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:33805
[junit4] 2> 3735220 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 33805
[junit4] 2> 3735240 INFO (zkConnectionManagerCallback-14932-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3735258 INFO (zkConnectionManagerCallback-14934-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3735324 INFO (zkConnectionManagerCallback-14936-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3735337 WARN (jetty-launcher-14937-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 3735337 INFO (jetty-launcher-14937-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 3735337 INFO (jetty-launcher-14937-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 3735338 INFO (jetty-launcher-14937-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 13-testing+0-builds.shipilev.net-openjdk-jdk-b845-20190430-jdk-1318
[junit4] 2> 3735357 WARN (jetty-launcher-14937-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 3735357 INFO (jetty-launcher-14937-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 3735357 INFO (jetty-launcher-14937-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 3735357 INFO (jetty-launcher-14937-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 13-testing+0-builds.shipilev.net-openjdk-jdk-b845-20190430-jdk-1318
[junit4] 2> 3735360 WARN (jetty-launcher-14937-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 3735361 INFO (jetty-launcher-14937-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 3735361 INFO (jetty-launcher-14937-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 3735361 INFO (jetty-launcher-14937-thread-3) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 13-testing+0-builds.shipilev.net-openjdk-jdk-b845-20190430-jdk-1318
[junit4] 2> 3735505 INFO (jetty-launcher-14937-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3735505 INFO (jetty-launcher-14937-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3735506 INFO (jetty-launcher-14937-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 3735506 INFO (jetty-launcher-14937-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3735506 INFO (jetty-launcher-14937-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3735506 INFO (jetty-launcher-14937-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 3735506 INFO (jetty-launcher-14937-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6135fc93{/solr,null,AVAILABLE}
[junit4] 2> 3735506 INFO (jetty-launcher-14937-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3735506 INFO (jetty-launcher-14937-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3735506 INFO (jetty-launcher-14937-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 3735506 INFO (jetty-launcher-14937-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@885c69c{/solr,null,AVAILABLE}
[junit4] 2> 3735507 INFO (jetty-launcher-14937-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@664b13f2{/solr,null,AVAILABLE}
[junit4] 2> 3735548 INFO (jetty-launcher-14937-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4e73a157{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:33643}
[junit4] 2> 3735548 INFO (jetty-launcher-14937-thread-3) [ ] o.e.j.s.Server Started @3735655ms
[junit4] 2> 3735548 INFO (jetty-launcher-14937-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33643}
[junit4] 2> 3735548 ERROR (jetty-launcher-14937-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3735548 INFO (jetty-launcher-14937-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3735548 INFO (jetty-launcher-14937-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 3735548 INFO (jetty-launcher-14937-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3735548 INFO (jetty-launcher-14937-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 3735548 INFO (jetty-launcher-14937-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-21T17:43:33.555724Z
[junit4] 2> 3735548 INFO (jetty-launcher-14937-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7e75c284{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:42895}
[junit4] 2> 3735548 INFO (jetty-launcher-14937-thread-2) [ ] o.e.j.s.Server Started @3735655ms
[junit4] 2> 3735548 INFO (jetty-launcher-14937-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42895}
[junit4] 2> 3735548 ERROR (jetty-launcher-14937-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3735548 INFO (jetty-launcher-14937-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3735549 INFO (jetty-launcher-14937-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 3735549 INFO (jetty-launcher-14937-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3735549 INFO (jetty-launcher-14937-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 3735549 INFO (jetty-launcher-14937-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-21T17:43:33.556070Z
[junit4] 2> 3735549 INFO (jetty-launcher-14937-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7c87a6e5{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:35763}
[junit4] 2> 3735549 INFO (jetty-launcher-14937-thread-1) [ ] o.e.j.s.Server Started @3735656ms
[junit4] 2> 3735549 INFO (jetty-launcher-14937-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35763}
[junit4] 2> 3735549 ERROR (jetty-launcher-14937-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3735549 INFO (jetty-launcher-14937-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3735549 INFO (jetty-launcher-14937-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 3735549 INFO (jetty-launcher-14937-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3735549 INFO (jetty-launcher-14937-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 3735549 INFO (jetty-launcher-14937-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-21T17:43:33.556839Z
[junit4] 2> 3735560 INFO (zkConnectionManagerCallback-14939-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3735561 INFO (jetty-launcher-14937-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3735577 INFO (zkConnectionManagerCallback-14943-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3735577 INFO (jetty-launcher-14937-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3735580 INFO (zkConnectionManagerCallback-14941-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3735582 INFO (jetty-launcher-14937-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3735673 INFO (jetty-launcher-14937-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 3735674 WARN (jetty-launcher-14937-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@206f5520[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3735699 INFO (jetty-launcher-14937-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 3735700 INFO (jetty-launcher-14937-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 3735702 WARN (jetty-launcher-14937-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@19527049[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3735702 WARN (jetty-launcher-14937-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@24e9f3f5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3735702 WARN (jetty-launcher-14937-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@62863386[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3735704 INFO (jetty-launcher-14937-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33805/solr
[junit4] 2> 3735709 WARN (jetty-launcher-14937-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6cfa3177[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3735710 INFO (jetty-launcher-14937-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33805/solr
[junit4] 2> 3735712 INFO (zkConnectionManagerCallback-14957-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3735721 WARN (jetty-launcher-14937-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@632656c1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3735722 INFO (jetty-launcher-14937-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33805/solr
[junit4] 2> 3735724 INFO (zkConnectionManagerCallback-14960-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3735736 INFO (zkConnectionManagerCallback-14964-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3735741 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100d3f377840007, likely client has closed socket
[junit4] 2> 3735743 INFO (zkConnectionManagerCallback-14966-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3735767 INFO (zkConnectionManagerCallback-14968-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3735776 INFO (jetty-launcher-14937-thread-2) [n:127.0.0.1:42895_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42895_solr
[junit4] 2> 3735777 INFO (jetty-launcher-14937-thread-2) [n:127.0.0.1:42895_solr ] o.a.s.c.Overseer Overseer (id=72290636673581066-127.0.0.1:42895_solr-n_0000000000) starting
[junit4] 2> 3735804 INFO (zkConnectionManagerCallback-14972-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3735810 INFO (jetty-launcher-14937-thread-1) [n:127.0.0.1:35763_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35763_solr
[junit4] 2> 3735815 INFO (jetty-launcher-14937-thread-3) [n:127.0.0.1:33643_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3735817 INFO (zkCallback-14971-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3735817 INFO (zkCallback-14967-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3735818 INFO (jetty-launcher-14937-thread-3) [n:127.0.0.1:33643_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:33643_solr as DOWN
[junit4] 2> 3735819 INFO (jetty-launcher-14937-thread-3) [n:127.0.0.1:33643_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 3735819 INFO (jetty-launcher-14937-thread-3) [n:127.0.0.1:33643_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33643_solr
[junit4] 2> 3735820 INFO (zkCallback-14967-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3735820 INFO (zkCallback-14971-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3735824 INFO (zkConnectionManagerCallback-14979-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3735826 INFO (jetty-launcher-14937-thread-2) [n:127.0.0.1:42895_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 3735827 INFO (jetty-launcher-14937-thread-2) [n:127.0.0.1:42895_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33805/solr ready
[junit4] 2> 3735832 INFO (zkCallback-14965-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3735833 INFO (OverseerStateUpdate-72290636673581066-127.0.0.1:42895_solr-n_0000000000) [n:127.0.0.1:42895_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:42895_solr
[junit4] 2> 3735836 INFO (zkConnectionManagerCallback-14986-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3735837 INFO (zkConnectionManagerCallback-14991-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3735837 INFO (jetty-launcher-14937-thread-2) [n:127.0.0.1:42895_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:42895_solr as DOWN
[junit4] 2> 3735838 INFO (jetty-launcher-14937-thread-2) [n:127.0.0.1:42895_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 3735838 INFO (jetty-launcher-14937-thread-2) [n:127.0.0.1:42895_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42895_solr
[junit4] 2> 3735839 INFO (jetty-launcher-14937-thread-3) [n:127.0.0.1:33643_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 3735839 INFO (zkCallback-14967-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 3735840 INFO (zkCallback-14965-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 3735840 INFO (jetty-launcher-14937-thread-3) [n:127.0.0.1:33643_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33805/solr ready
[junit4] 2> 3735847 INFO (jetty-launcher-14937-thread-1) [n:127.0.0.1:35763_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 3735848 INFO (jetty-launcher-14937-thread-1) [n:127.0.0.1:35763_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33805/solr ready
[junit4] 2> 3735854 INFO (zkCallback-14990-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 3735860 INFO (zkCallback-14978-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 3735864 INFO (zkCallback-14971-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 3735896 INFO (jetty-launcher-14937-thread-1) [n:127.0.0.1:35763_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 3735952 INFO (jetty-launcher-14937-thread-2) [n:127.0.0.1:42895_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 3735964 INFO (jetty-launcher-14937-thread-3) [n:127.0.0.1:33643_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 3735999 INFO (jetty-launcher-14937-thread-1) [n:127.0.0.1:35763_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35763.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@756c7f85
[junit4] 2> 3736025 INFO (jetty-launcher-14937-thread-2) [n:127.0.0.1:42895_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42895.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@756c7f85
[junit4] 2> 3736054 INFO (jetty-launcher-14937-thread-3) [n:127.0.0.1:33643_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33643.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@756c7f85
[junit4] 2> 3736081 INFO (jetty-launcher-14937-thread-1) [n:127.0.0.1:35763_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35763.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@756c7f85
[junit4] 2> 3736081 INFO (jetty-launcher-14937-thread-1) [n:127.0.0.1:35763_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35763.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@756c7f85
[junit4] 2> 3736085 INFO (jetty-launcher-14937-thread-2) [n:127.0.0.1:42895_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42895.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@756c7f85
[junit4] 2> 3736086 INFO (jetty-launcher-14937-thread-2) [n:127.0.0.1:42895_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42895.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@756c7f85
[junit4] 2> 3736086 INFO (jetty-launcher-14937-thread-1) [n:127.0.0.1:35763_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_35CADB86504033DA-001/tempDir-001/node1/.
[junit4] 2> 3736089 INFO (jetty-launcher-14937-thread-2) [n:127.0.0.1:42895_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_35CADB86504033DA-001/tempDir-001/node2/.
[junit4] 2> 3736099 INFO (jetty-launcher-14937-thread-3) [n:127.0.0.1:33643_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33643.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@756c7f85
[junit4] 2> 3736099 INFO (jetty-launcher-14937-thread-3) [n:127.0.0.1:33643_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33643.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@756c7f85
[junit4] 2> 3736101 INFO (jetty-launcher-14937-thread-3) [n:127.0.0.1:33643_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_35CADB86504033DA-001/tempDir-001/node3/.
[junit4] 2> 3736616 INFO (zkConnectionManagerCallback-14999-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3736641 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 3736642 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33805/solr ready
[junit4] 2> 3736677 INFO (qtp2109647419-37998) [n:127.0.0.1:35763_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> 3736701 INFO (OverseerThreadFactory-13173-thread-1-processing-n:127.0.0.1:42895_solr) [n:127.0.0.1:42895_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection authCollection
[junit4] 2> 3736816 INFO (OverseerStateUpdate-72290636673581066-127.0.0.1:42895_solr-n_0000000000) [n:127.0.0.1:42895_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":"http://127.0.0.1:42895/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 3736820 INFO (OverseerStateUpdate-72290636673581066-127.0.0.1:42895_solr-n_0000000000) [n:127.0.0.1:42895_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_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:35763/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 3736822 INFO (OverseerStateUpdate-72290636673581066-127.0.0.1:42895_solr-n_0000000000) [n:127.0.0.1:42895_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":"http://127.0.0.1:33643/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 3737963 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr x:authCollection_shard2_replica_n3] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=authCollection_shard2_replica_n3&action=CREATE&numShards=3&collection=authCollection&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 3737964 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr x:authCollection_shard2_replica_n3] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 3737971 INFO (qtp286104854-37999) [n:127.0.0.1:33643_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> 3737972 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr x:authCollection_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&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> 3738999 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 3739001 INFO (qtp286104854-37999) [n:127.0.0.1:33643_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> 3739009 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 3739019 INFO (qtp286104854-37999) [n:127.0.0.1:33643_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> 3739022 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.s.IndexSchema [authCollection_shard2_replica_n3] Schema name=minimal
[junit4] 2> 3739022 INFO (qtp286104854-37999) [n:127.0.0.1:33643_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> 3739022 INFO (qtp286104854-37999) [n:127.0.0.1:33643_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> 3739023 INFO (qtp286104854-37999) [n:127.0.0.1:33643_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33643.solr.core.authCollection.shard3.replica_n4' (registry 'solr.core.authCollection.shard3.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@756c7f85
[junit4] 2> 3739023 INFO (qtp286104854-37999) [n:127.0.0.1:33643_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 [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_35CADB86504033DA-001/tempDir-001/node3/authCollection_shard3_replica_n4], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_35CADB86504033DA-001/tempDir-001/node3/./authCollection_shard3_replica_n4/data/]
[junit4] 2> 3739025 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 3739025 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard2_replica_n3' using configuration from collection authCollection, trusted=true
[junit4] 2> 3739025 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35763.solr.core.authCollection.shard2.replica_n3' (registry 'solr.core.authCollection.shard2.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@756c7f85
[junit4] 2> 3739025 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.c.SolrCore [[authCollection_shard2_replica_n3] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_35CADB86504033DA-001/tempDir-001/node1/authCollection_shard2_replica_n3], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_35CADB86504033DA-001/tempDir-001/node1/./authCollection_shard2_replica_n3/data/]
[junit4] 2> 3739026 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.s.IndexSchema [authCollection_shard1_replica_n1] Schema name=minimal
[junit4] 2> 3739028 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 3739028 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 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> 3739029 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42895.solr.core.authCollection.shard1.replica_n1' (registry 'solr.core.authCollection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@756c7f85
[junit4] 2> 3739029 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.c.SolrCore [[authCollection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_35CADB86504033DA-001/tempDir-001/node2/authCollection_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_35CADB86504033DA-001/tempDir-001/node2/./authCollection_shard1_replica_n1/data/]
[junit4] 2> 3739110 INFO (qtp286104854-37999) [n:127.0.0.1:33643_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> 3739110 INFO (qtp286104854-37999) [n:127.0.0.1:33643_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> 3739219 INFO (qtp286104854-37999) [n:127.0.0.1:33643_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 3739219 INFO (qtp286104854-37999) [n:127.0.0.1:33643_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 3739241 INFO (qtp286104854-37999) [n:127.0.0.1:33643_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Searcher@cf5e42b[authCollection_shard3_replica_n4] main]
[junit4] 2> 3739244 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 3739244 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 3739252 INFO (qtp286104854-37999) [n:127.0.0.1:33643_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> 3739253 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 3739253 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 3739253 INFO (qtp286104854-37999) [n:127.0.0.1:33643_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> 3739254 INFO (qtp286104854-37999) [n:127.0.0.1:33643_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> 3739254 INFO (qtp286104854-37999) [n:127.0.0.1:33643_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1634164400205070336
[junit4] 2> 3739258 INFO (searcherExecutor-13186-thread-1-processing-n:127.0.0.1:33643_solr x:authCollection_shard3_replica_n4 c:authCollection s:shard3 r:core_node6) [n:127.0.0.1:33643_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.SolrCore [authCollection_shard3_replica_n4] Registered new searcher Searcher@cf5e42b[authCollection_shard3_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3739260 INFO (qtp286104854-37999) [n:127.0.0.1:33643_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.ZkShardTerms Successful update of terms at /collections/authCollection/terms/shard3 to Terms{values={core_node6=0}, version=0}
[junit4] 2> 3739260 INFO (qtp286104854-37999) [n:127.0.0.1:33643_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/authCollection/leaders/shard3
[junit4] 2> 3739264 INFO (qtp286104854-37999) [n:127.0.0.1:33643_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 3739264 INFO (qtp286104854-37999) [n:127.0.0.1:33643_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 3739264 INFO (qtp286104854-37999) [n:127.0.0.1:33643_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:33643/solr/authCollection_shard3_replica_n4/
[junit4] 2> 3739264 INFO (qtp286104854-37999) [n:127.0.0.1:33643_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 3739265 INFO (qtp286104854-37999) [n:127.0.0.1:33643_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.SyncStrategy http://127.0.0.1:33643/solr/authCollection_shard3_replica_n4/ has no replicas
[junit4] 2> 3739265 INFO (qtp286104854-37999) [n:127.0.0.1:33643_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/authCollection/leaders/shard3/leader after winning as /collections/authCollection/leader_elect/shard3/election/72290636673581065-core_node6-n_0000000000
[junit4] 2> 3739266 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@62e0a18f[authCollection_shard2_replica_n3] main]
[junit4] 2> 3739267 INFO (qtp286104854-37999) [n:127.0.0.1:33643_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:33643/solr/authCollection_shard3_replica_n4/ shard3
[junit4] 2> 3739270 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 3739270 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 3739281 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 3739281 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1634164400233381888
[junit4] 2> 3739285 INFO (searcherExecutor-13187-thread-1-processing-n:127.0.0.1:35763_solr x:authCollection_shard2_replica_n3 c:authCollection s:shard2 r:core_node5) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.c.SolrCore [authCollection_shard2_replica_n3] Registered new searcher Searcher@62e0a18f[authCollection_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3739292 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.c.ZkShardTerms Successful update of terms at /collections/authCollection/terms/shard2 to Terms{values={core_node5=0}, version=0}
[junit4] 2> 3739293 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/authCollection/leaders/shard2
[junit4] 2> 3739296 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 3739296 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 3739296 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:35763/solr/authCollection_shard2_replica_n3/
[junit4] 2> 3739296 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 3739296 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.c.SyncStrategy http://127.0.0.1:35763/solr/authCollection_shard2_replica_n3/ has no replicas
[junit4] 2> 3739296 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/authCollection/leaders/shard2/leader after winning as /collections/authCollection/leader_elect/shard2/election/72290636673581067-core_node5-n_0000000000
[junit4] 2> 3739298 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:35763/solr/authCollection_shard2_replica_n3/ shard2
[junit4] 2> 3739305 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 3739306 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 3739308 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 3739309 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 3739311 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@9b3fd4a[authCollection_shard1_replica_n1] main]
[junit4] 2> 3739312 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 3739312 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 3739313 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 3739313 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1634164400266936320
[junit4] 2> 3739317 INFO (searcherExecutor-13188-thread-1-processing-n:127.0.0.1:42895_solr x:authCollection_shard1_replica_n1 c:authCollection s:shard1 r:core_node2) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.c.SolrCore [authCollection_shard1_replica_n1] Registered new searcher Searcher@9b3fd4a[authCollection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3739324 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/authCollection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 3739324 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/authCollection/leaders/shard1
[junit4] 2> 3739327 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 3739327 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 3739327 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:42895/solr/authCollection_shard1_replica_n1/
[junit4] 2> 3739327 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 3739327 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:42895/solr/authCollection_shard1_replica_n1/ has no replicas
[junit4] 2> 3739328 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 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/72290636673581066-core_node2-n_0000000000
[junit4] 2> 3739329 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:42895/solr/authCollection_shard1_replica_n1/ shard1
[junit4] 2> 3739430 INFO (zkCallback-14967-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3739430 INFO (zkCallback-14965-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3739430 INFO (zkCallback-14971-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3739431 INFO (qtp286104854-37999) [n:127.0.0.1:33643_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 3739431 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 3739431 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 3739448 INFO (zkCallback-14965-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3739452 INFO (zkCallback-14967-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3739459 INFO (zkCallback-14971-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3739464 INFO (qtp286104854-37999) [n:127.0.0.1:33643_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={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} status=0 QTime=1492
[junit4] 2> 3739468 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=authCollection_shard2_replica_n3&action=CREATE&numShards=3&collection=authCollection&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1505
[junit4] 2> 3739468 INFO (qtp311362511-38006) [n:127.0.0.1:42895_solr c:authCollection s:shard1 r:core_node2 x:authCollection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=authCollection_shard1_replica_n1&action=CREATE&numShards=3&collection=authCollection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1497
[junit4] 2> 3739557 INFO (zkCallback-14971-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3739557 INFO (zkCallback-14967-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3739557 INFO (zkCallback-14971-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3739557 INFO (zkCallback-14967-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3739557 INFO (zkCallback-14965-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3739557 INFO (zkCallback-14965-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3739563 INFO (zkCallback-14971-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3739576 INFO (zkCallback-14965-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3739584 INFO (zkCallback-14967-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3740794 INFO (qtp2109647419-37998) [n:127.0.0.1:35763_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 3740794 INFO (qtp2109647419-37998) [n:127.0.0.1:35763_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=authCollection&nrtReplicas=1&action=CREATE&numShards=3&wt=javabin&version=2} status=0 QTime=4119
[junit4] 2> 3740800 INFO (qtp2109647419-37995) [n:127.0.0.1:35763_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/security/authentication params={} status=0 QTime=0
[junit4] 2> 3740802 INFO (zkCallback-14965-thread-3) [ ] o.a.s.c.CoreContainer Security node changed, reloading security.json
[junit4] 2> 3740802 INFO (zkCallback-14971-thread-3) [ ] o.a.s.c.CoreContainer Security node changed, reloading security.json
[junit4] 2> 3740803 INFO (qtp2109647419-37998) [n:127.0.0.1:35763_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/security/authentication params={} status=0 QTime=0
[junit4] 2> 3740803 INFO (zkCallback-14965-thread-3) [ ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin
[junit4] 2> 3740806 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1317324476
[junit4] 2> 3740806 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:35763_solr
[junit4] 2> 3740808 INFO (zkCallback-14971-thread-3) [ ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin
[junit4] 2> 3740809 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 3740809 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:35763_solr as DOWN
[junit4] 2> 3740812 INFO (zkCallback-14967-thread-3) [ ] o.a.s.c.CoreContainer Security node changed, reloading security.json
[junit4] 2> 3740812 INFO (zkCallback-14967-thread-3) [ ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin
[junit4] 2> 3740820 INFO (zkCallback-14965-thread-3) [ ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 3740821 INFO (zkCallback-14967-thread-3) [ ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 3740825 INFO (zkCallback-14971-thread-3) [ ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 3740840 INFO (coreCloseExecutor-13201-thread-1) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.c.SolrCore [authCollection_shard2_replica_n3] CLOSING SolrCore org.apache.solr.core.SolrCore@3d265692
[junit4] 2> 3740840 INFO (coreCloseExecutor-13201-thread-1) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.authCollection.shard2.replica_n3, tag=3d265692
[junit4] 2> 3740840 INFO (coreCloseExecutor-13201-thread-1) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@29bf0ba4: rootName = solr_35763, domain = solr.core.authCollection.shard2.replica_n3, service url = null, agent id = null] for registry solr.core.authCollection.shard2.replica_n3 / com.codahale.metrics.MetricRegistry@7b0620b7
[junit4] 2> 3740857 INFO (coreCloseExecutor-13201-thread-1) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.authCollection.shard2.leader, tag=3d265692
[junit4] 2> 3740861 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 3740861 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1752a56: rootName = solr_35763, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@6ac794f2
[junit4] 2> 3740877 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 3740877 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1daeb12c: rootName = solr_35763, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@5fe169ef
[junit4] 2> 3740886 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 3740886 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7be137f3: rootName = solr_35763, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@54d043c4
[junit4] 2> 3740886 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 3740910 INFO (zkCallback-14990-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 3740910 INFO (zkCallback-14967-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 3740910 INFO (zkCallback-14998-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 3740911 INFO (zkCallback-14978-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 3740913 INFO (zkCallback-14965-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 3740916 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7c87a6e5{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 3740916 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6135fc93{/solr,null,UNAVAILABLE}
[junit4] 2> 3740916 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 3740917 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 3740918 WARN (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 3740918 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 35763 try number 1 ...
[junit4] 2> 3740918 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 13-testing+0-builds.shipilev.net-openjdk-jdk-b845-20190430-jdk-1318
[junit4] 2> 3740925 INFO (zkCallback-14965-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3740925 INFO (zkCallback-14965-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3740925 INFO (zkCallback-14965-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3740925 INFO (zkCallback-14967-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3740925 INFO (zkCallback-14967-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3740925 INFO (zkCallback-14967-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3740948 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3740948 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3740948 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 3740952 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@59179739{/solr,null,AVAILABLE}
[junit4] 2> 3740953 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@62b3c3ea{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:35763}
[junit4] 2> 3740953 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.e.j.s.Server Started @3741060ms
[junit4] 2> 3740953 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35763}
[junit4] 2> 3740953 ERROR (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3740953 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3740954 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 3740954 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3740954 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 3740954 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-21T17:43:38.961173Z
[junit4] 2> 3740964 INFO (zkConnectionManagerCallback-15008-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3740965 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3741044 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 3741045 WARN (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@16e1745e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3741057 WARN (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@51e6ef1c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3741059 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33805/solr
[junit4] 2> 3741076 INFO (zkConnectionManagerCallback-15015-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3741120 INFO (zkConnectionManagerCallback-15017-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3741133 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [n:127.0.0.1:35763_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 3741137 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [n:127.0.0.1:35763_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:35763_solr as DOWN
[junit4] 2> 3741138 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [n:127.0.0.1:35763_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 3741138 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [n:127.0.0.1:35763_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35763_solr
[junit4] 2> 3741138 INFO (zkCallback-14990-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 3741138 INFO (zkCallback-14998-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 3741139 INFO (zkCallback-14978-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 3741140 INFO (zkCallback-14965-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 3741140 INFO (zkCallback-14967-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 3741156 INFO (zkCallback-15016-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 3741182 INFO (zkConnectionManagerCallback-15024-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3741187 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [n:127.0.0.1:35763_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 3741190 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [n:127.0.0.1:35763_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33805/solr ready
[junit4] 2> 3741195 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [n:127.0.0.1:35763_solr ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin
[junit4] 2> 3741195 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [n:127.0.0.1:35763_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 3741240 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [n:127.0.0.1:35763_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 3741249 INFO (zkCallback-14967-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3741249 INFO (zkCallback-14967-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3741249 INFO (zkCallback-14967-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3741249 INFO (zkCallback-14965-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3741249 INFO (zkCallback-14965-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3741249 INFO (zkCallback-14965-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3741283 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [n:127.0.0.1:35763_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35763.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@756c7f85
[junit4] 2> 3741313 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [n:127.0.0.1:35763_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35763.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@756c7f85
[junit4] 2> 3741314 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [n:127.0.0.1:35763_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35763.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@756c7f85
[junit4] 2> 3741317 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [n:127.0.0.1:35763_solr ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_35CADB86504033DA-001/tempDir-001/node1/.
[junit4] 2> 3741317 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[35CADB86504033DA]) [n:127.0.0.1:35763_solr ] o.a.s.c.CorePropertiesLocator Cores are: [authCollection_shard2_replica_n3]
[junit4] 2> 3741336 INFO (coreLoadExecutor-13211-thread-1-processing-n:127.0.0.1:35763_solr) [n:127.0.0.1:35763_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 3741345 INFO (coreLoadExecutor-13211-thread-1-processing-n:127.0.0.1:35763_solr) [n:127.0.0.1:35763_solr ] o.a.s.s.IndexSchema [authCollection_shard2_replica_n3] Schema name=minimal
[junit4] 2> 3741347 INFO (coreLoadExecutor-13211-thread-1-processing-n:127.0.0.1:35763_solr) [n:127.0.0.1:35763_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 3741397 INFO (coreLoadExecutor-13211-thread-1-processing-n:127.0.0.1:35763_solr) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 3741411 INFO (coreLoadExecutor-13211-thread-1-processing-n:127.0.0.1:35763_solr) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.s.IndexSchema [authCollection_shard2_replica_n3] Schema name=minimal
[junit4] 2> 3741414 INFO (coreLoadExecutor-13211-thread-1-processing-n:127.0.0.1:35763_solr) [n:127.0.0.1:35763_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n3] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 3741414 INFO (coreLoadExecutor-13211-thread-1-processing-n:127.0.0.1:35763_solr) [n:127.0.0.1:35763_solr c:authCollection s:sha
[...truncated too long message...]
sicAuthIntegrationTest.testBasicAuth <<<
[junit4] > Throwable #1: java.lang.AssertionError: must have failed
[junit4] > at __randomizedtesting.SeedInfo.seed([35CADB86504033DA:89A4AD94F413B0A0]:0)
[junit4] > at org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth(BasicAuthIntegrationTest.java:204)
[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:830)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_35CADB86504033DA-001
[junit4] 2> May 21, 2019 5:43:45 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {}, docValues:{}, maxPointsInLeafNode=1714, maxMBSortInHeap=5.422145608809619, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@13564b92), locale=en-SG, timezone=Mexico/General
[junit4] 2> NOTE: Linux 4.18.0-17-generic amd64/Aleksey Shipilev 13-testing (64-bit)/cpus=8,threads=1,free=260087088,total=507510784
[junit4] 2> NOTE: All tests run in this JVM: [PreAnalyzedUpdateProcessorTest, IgnoreLargeDocumentProcessorFactoryTest, PreAnalyzedFieldTest, DistributedMLTComponentTest, ConfigSetsAPITest, TestRandomFaceting, ReplicationFactorTest, TestLFUCache, LargeFieldTest, TestExceedMaxTermLength, TestCloudJSONFacetJoinDomain, TestSolrConfigHandler, TestRemoteStreaming, TestSimGenericDistributedQueue, Tagger2Test, TermVectorComponentDistributedTest, OrderedExecutorTest, TestBulkSchemaConcurrent, TlogReplayBufferedWhileIndexingTest, VMParamsZkACLAndCredentialsProvidersTest, ManagedSchemaRoundRobinCloudTest, TokenizerChainTest, SoftAutoCommitTest, SolrPluginUtilsTest, HighlighterMaxOffsetTest, DistributedFacetSimpleRefinementLongTailTest, TriggerEventQueueTest, TestPayloadCheckQParserPlugin, ComputePlanActionTest, BasicFunctionalityTest, TestUtils, CoreAdminOperationTest, OverseerTaskQueueTest, DistributedFacetExistsSmallTest, PeerSyncWithLeaderAndIndexFingerprintCachingTest, CreateRoutedAliasTest, TestReloadAndDeleteDocs, HdfsRecoverLeaseTest, TestSort, ProtectedTermFilterFactoryTest, RemoteQueryErrorTest, ClusterStateTest, DocValuesMultiTest, TestRequestForwarding, TestSolrCloudWithHadoopAuthPlugin, TestSchemaVersionResource, CollectionStateFormat2Test, TestExactSharedStatsCache, TestStressVersions, TestNestedDocsSort, TestSystemCollAutoCreate, TestHighlightDedupGrouping, TestOrdValues, SpellingQueryConverterTest, TestUseDocValuesAsStored2, SignificantTermsQParserPluginTest, TestCoreDiscovery, TestInPlaceUpdatesDistrib, DistributedQueryElevationComponentTest, ConnectionReuseTest, TestCloudPivotFacet, SuggesterFSTTest, JSONWriterTest, RegexBytesRefFilterTest, BinaryUpdateRequestHandlerTest, TestSchemalessBufferedUpdates, TestCloudDeleteByQuery, TestSearcherReuse, ShardRoutingCustomTest, DisMaxRequestHandlerTest, SearchHandlerTest, TestReload, BJQParserTest, TestNumericTokenStream, TestComplexPhraseQParserPlugin, TestPerFieldSimilarity, TestSubQueryTransformerDistrib, TestNumericTerms64, TestShortCircuitedRequests, TestConfigsApi, CollectionsAPIDistributedZkTest, LeaderFailureAfterFreshStartTest, HdfsDirectoryFactoryTest, TestCollationField, HttpTriggerListenerTest, AlternateDirectoryTest, AnalysisErrorHandlingTest, MetricsConfigTest, SchemaApiFailureTest, TestSolrCLIRunExample, SparseHLLTest, TestLocalFSCloudBackupRestore, ZkSolrClientTest, TestDistributedMap, TestMaxScoreQueryParser, AnalyticsQueryTest, DateFieldTest, AtomicUpdateRemovalJavabinTest, LukeRequestHandlerTest, MigrateRouteKeyTest, DirectUpdateHandlerTest, DocValuesNotIndexedTest, CdcrBootstrapTest, SolrMetricReporterTest, TestSolrCloudWithDelegationTokens, SystemCollectionCompatTest, StatelessScriptUpdateProcessorFactoryTest, TestBM25SimilarityFactory, TestHashPartitioner, DirectSolrConnectionTest, OverseerModifyCollectionTest, TestChildDocTransformerHierarchy, BadComponentTest, TestFieldResource, TestDocTermOrdsUninvertLimit, RAMDirectoryFactoryTest, SystemInfoHandlerTest, CollectionReloadTest, ImplicitSnitchTest, TestCloudRecovery2, ResponseHeaderTest, ScriptEngineTest, HttpPartitionTest, TestDFRSimilarityFactory, BigEndianAscendingWordSerializerTest, SolrInfoBeanTest, MinimalSchemaTest, TestFastLRUCache, SolrIndexMetricsTest, TestJmxIntegration, TestPrepRecovery, HDFSCollectionsAPITest, TestJsonRequest, TestDirectoryFactory, TestObjectReleaseTracker, TestSolrIndexConfig, TestFuzzyAnalyzedSuggestions, TestRetrieveFieldsOptimizer, BlockJoinFacetSimpleTest, UniqFieldsUpdateProcessorFactoryTest, AssignBackwardCompatibilityTest, BasicDistributedZk2Test, BasicZkTest, ChaosMonkeyNothingIsSafeTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, DeleteNodeTest, DeleteStatusTest, DistribDocExpirationUpdateProcessorTest, DistributedQueueTest, LeaderElectionContextKeyTest, LeaderElectionTest, MultiSolrCloudTestCaseTest, MultiThreadedOCPTest, NestedShardedAtomicUpdateTest, RecoveryZkTest, RollingRestartTest, SolrXmlInZkTest, SplitShardTest, TestConfigSetsAPIZkFailure, TestDeleteCollectionOnDownNodes, TestLeaderElectionZkExpiry, TestLockTree, TestMiniSolrCloudClusterSSL, TestPullReplicaErrorHandling, TestSolrCloudWithSecureImpersonation, TestStressCloudBlindAtomicUpdates, TestTlogReplica, TestTolerantUpdateProcessorCloud, TestWithCollection, TestRequestStatusCollectionAPI, AutoAddReplicasIntegrationTest, NodeAddedTriggerIntegrationTest, NodeLostTriggerIntegrationTest, NodeLostTriggerTest, ScheduledMaintenanceTriggerTest, ScheduledTriggerIntegrationTest, SystemLogListenerTest, TriggerSetPropertiesIntegrationTest, TestSimClusterStateProvider, TestSimExecutePlanAction, TestSimNodeAddedTrigger, TestSimPolicyCloud, BaseCdcrDistributedZkTest, CdcrReplicationHandlerTest, CdcrRequestHandlerTest, CdcrVersionReplicationTest, DirectoryFactoryTest, SolrCoreCheckLockOnStartupTest, TestBackupRepositoryFactory, TestBadConfig, TestConfig, TestConfigOverlay, TestConfigSetImmutable, TestInfoStreamLogging, TestNRTOpen, TestQuerySenderListener, DocumentAnalysisRequestHandlerTest, JsonLoaderTest, PingRequestHandlerTest, TestReplicationHandler, V2StandaloneTest, XmlUpdateRequestHandlerTest, AdminHandlersProxyTest, AutoscalingHistoryHandlerTest, PropertiesRequestHandlerTest, SecurityConfHandlerTest, SegmentsInfoRequestHandlerTest, CustomTermsComponentTest, DistributedFacetPivotSmallAdvancedTest, DistributedSuggestComponentTest, PhrasesIdentificationComponentTest, ReplicaListTransformerTest, ResponseLogComponentTest, SearchHandlerTest, SuggestComponentContextFilterQueryTest, TestHttpShardHandlerFactory, TestPivotHelperCode, TestTrackingShardHandlerFactory, JavabinLoaderTest, FastVectorHighlighterTest, TestUnifiedSolrHighlighter, TestLegacyField, TestLegacyFieldReuse, TestLegacyNumericUtils, TestNumericRangeQuery32, SolrJmxReporterCloudTest, SolrJmxReporterTest, SolrShardReporterTest, TestUnInvertedFieldException, TestWriterPerf, TestCSVResponseWriter, TestJavabinTupleStreamParser, TestPHPSerializedResponseWriter, TestBulkSchemaAPI, TestManagedSynonymFilterFactory, ChangedSchemaMergeTest, EnumFieldTest, IndexSchemaRuntimeFieldTest, PolyFieldTest, TestManagedSchema, QueryParsingTest, RankQueryTest, SpatialFilterTest, TestAddFieldRealTimeGet, TestCollapseQParserPlugin, TestFilteredDocIdSet, TestFoldingMultitermQuery, TestGraphTermsQParserPlugin, TestHashQParserPlugin, TestIndexSearcher, TestMissingGroups, TestMultiWordSynonyms, TestNoOpRegenerator, TestPseudoReturnFields, TestRangeQuery, TestJsonFacetsStatsParsing, SortByFunctionTest, TestLMDirichletSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestLegacyBM25SimilarityFactory, TestDefaultStatsCache, AuditLoggerPluginTest, BasicAuthIntegrationTest]
[junit4] Completed [781/858 (2!)] on J1 in 14.60s, 1 test, 1 failure <<< FAILURES!
[...truncated 43766 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/24124/consoleText
[repro] Revision: c756b50ae4324b5692b2956c7a5d484122ac3016
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=SolrRrdBackendFactoryTest -Dtests.method=testBasic -Dtests.seed=35CADB86504033DA -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=mr-IN -Dtests.timezone=Zulu -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] Repro line: ant test -Dtestcase=BasicAuthIntegrationTest -Dtests.method=testBasicAuth -Dtests.seed=35CADB86504033DA -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-SG -Dtests.timezone=Mexico/General -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] BasicAuthIntegrationTest
[repro] SolrRrdBackendFactoryTest
[repro] ant compile-test
[...truncated 3310 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=10 -Dtests.class="*.BasicAuthIntegrationTest|*.SolrRrdBackendFactoryTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC" -Dtests.seed=35CADB86504033DA -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-SG -Dtests.timezone=Mexico/General -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 95 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.metrics.rrd.SolrRrdBackendFactoryTest
[repro] 0/5 failed: org.apache.solr.security.BasicAuthIntegrationTest
[repro] Exiting with code 0
[...truncated 43 lines...]