You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2020/03/22 16:59:19 UTC
[JENKINS] Lucene-Solr-Tests-master - Build # 4473 - Unstable
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/4473/
1 tests failed.
FAILED: org.apache.solr.handler.component.DistributedExpandComponentTest.test
Error Message:
Error from server at http://127.0.0.1:45427/api/kj/collection1: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=utf-8"/> <title>Error 404 Not Found</title> </head> <body><h2>HTTP ERROR 404 Not Found</h2> <table> <tr><th>URI:</th><td>/solr/____v2/kj/collection1/update</td></tr> <tr><th>STATUS:</th><td>404</td></tr> <tr><th>MESSAGE:</th><td>Not Found</td></tr> <tr><th>SERVLET:</th><td>-</td></tr> </table> <hr><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.4.24.v20191120</a><hr/> </body> </html>
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:45427/api/kj/collection1: Expected mime type application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=utf-8"/>
<title>Error 404 Not Found</title>
</head>
<body><h2>HTTP ERROR 404 Not Found</h2>
<table>
<tr><th>URI:</th><td>/solr/____v2/kj/collection1/update</td></tr>
<tr><th>STATUS:</th><td>404</td></tr>
<tr><th>MESSAGE:</th><td>Not Found</td></tr>
<tr><th>SERVLET:</th><td>-</td></tr>
</table>
<hr><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.4.24.v20191120</a><hr/>
</body>
</html>
at __randomizedtesting.SeedInfo.seed([23E85FDA59314BCC:ABBC6000F7CD2634]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:629)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
at org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:940)
at org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:903)
at org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:918)
at org.apache.solr.BaseDistributedSearchTestCase.del(BaseDistributedSearchTestCase.java:609)
at org.apache.solr.handler.component.DistributedExpandComponentTest.test(DistributedExpandComponentTest.java:52)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
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:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
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:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:834)
Build Log:
[...truncated 14729 lines...]
[junit4] Suite: org.apache.solr.handler.component.DistributedExpandComponentTest
[junit4] 2> 2242161 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/server/solr/configsets/_default/conf'
[junit4] 2> 2242161 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2242162 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/data-dir-241-001
[junit4] 2> 2242162 WARN (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=12 numCloses=12
[junit4] 2> 2242162 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2242163 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 2242163 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /api/kj
[junit4] 2> 2242163 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 2242170 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2242188 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.s.IndexSchema Schema name=example
[junit4] 2> 2242229 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.s.IndexSchema Loaded schema example/1.1 with uniqueid field id
[junit4] 2> 2243465 WARN (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.f.DistribPackageStore Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr]. Features requiring this directory may fail.
[junit4] 2> => java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/filestore" "write")
[junit4] 2> at java.base/java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
[junit4] 2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/filestore" "write")
[junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:?]
[junit4] 2> at java.security.AccessController.checkPermission(AccessController.java:897) ~[?:?]
[junit4] 2> at java.lang.SecurityManager.checkPermission(SecurityManager.java:322) ~[?:?]
[junit4] 2> at java.lang.SecurityManager.checkWrite(SecurityManager.java:752) ~[?:?]
[junit4] 2> at java.io.File.mkdir(File.java:1323) ~[?:?]
[junit4] 2> at java.io.File.mkdirs(File.java:1355) ~[?:?]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.ensurePackageStoreDir(DistribPackageStore.java:519) ~[java/:?]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.<init>(DistribPackageStore.java:77) ~[java/:?]
[junit4] 2> at org.apache.solr.filestore.PackageStoreAPI.<init>(PackageStoreAPI.java:79) ~[java/:?]
[junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:614) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:170) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:142) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:148) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:111) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:806) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:796) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:657) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:646) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.component.DistributedExpandComponentTest.setUpBeforeClass(DistributedExpandComponentTest.java:44) ~[test/:?]
[junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
[junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
[junit4] 2> at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:882) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:834) [?:?]
[junit4] 2> 2243466 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2243467 WARN (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5c000a1e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2243467 WARN (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5c000a1e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2243470 WARN (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4fb22847[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2243470 WARN (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4fb22847[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2243471 WARN (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 2243487 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 2243487 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2243503 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408
[junit4] 2> 2243510 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408
[junit4] 2> 2243510 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408
[junit4] 2> 2243516 INFO (coreLoadExecutor-11810-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2243533 INFO (coreLoadExecutor-11810-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=example
[junit4] 2> 2243585 INFO (coreLoadExecutor-11810-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema example/1.1 with uniqueid field id
[junit4] 2> 2243591 INFO (coreLoadExecutor-11810-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 2243591 INFO (coreLoadExecutor-11810-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408
[junit4] 2> 2243592 INFO (coreLoadExecutor-11810-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/data-dir-241-001/]
[junit4] 2> 2243593 INFO (coreLoadExecutor-11810-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=63.595703125, floorSegmentMB=1.3916015625, forceMergeDeletesPctAllowed=4.290231389902482, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=38.46114751103808
[junit4] 2> 2243687 INFO (coreLoadExecutor-11810-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2243687 INFO (coreLoadExecutor-11810-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2243688 INFO (coreLoadExecutor-11810-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2243688 INFO (coreLoadExecutor-11810-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2243689 INFO (coreLoadExecutor-11810-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=99.2158203125, floorSegmentMB=1.04296875, forceMergeDeletesPctAllowed=8.124498729580251, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6921850569745925, deletesPctAllowed=46.85915773492842
[junit4] 2> 2243689 INFO (coreLoadExecutor-11810-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@10d3b517[collection1] main]
[junit4] 2> 2243690 WARN (coreLoadExecutor-11810-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 2243690 INFO (coreLoadExecutor-11810-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2243690 INFO (coreLoadExecutor-11810-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct, classname=DirectSolrSpellChecker, field=a_s, minQueryLength=3}
[junit4] 2> 2243691 INFO (coreLoadExecutor-11810-thread-1) [ x:collection1 ] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/elevate.xml
[junit4] 2> 2243694 INFO (coreLoadExecutor-11810-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2243695 INFO (coreLoadExecutor-11810-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1661882557188276224
[junit4] 2> 2243696 INFO (searcherExecutor-11811-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2243697 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 2243697 INFO (searcherExecutor-11811-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@10d3b517[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2243854 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/control/cores/collection1
[junit4] 2> 2243855 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2243855 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2243855 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 2243858 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2243858 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2243858 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2243859 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@28246ba9{/api/kj,null,AVAILABLE}
[junit4] 2> 2243859 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@503cd7be{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:45427}
[junit4] 2> 2243859 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.Server Started @2243907ms
[junit4] 2> 2243859 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema11.xml, hostContext=/api/kj, solrconfig=solrconfig-collapseqparser.xml, hostPort=45427, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/control/cores}
[junit4] 2> 2243860 ERROR (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2243860 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2243860 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2243860 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 2243860 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr
[junit4] 2> 2243860 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-22T16:31:51.839301Z
[junit4] 2> 2243860 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/control/solr.xml
[junit4] 2> 2243864 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2244511 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2244512 WARN (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@28242c48[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2244512 WARN (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@28242c48[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2244515 WARN (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@50dff365[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2244515 WARN (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@50dff365[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2244517 WARN (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 2244535 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2244535 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2244558 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408
[junit4] 2> 2244568 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408
[junit4] 2> 2244568 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408
[junit4] 2> 2244570 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/control/cores
[junit4] 2> 2244570 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2244576 INFO (coreLoadExecutor-11825-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2244595 INFO (coreLoadExecutor-11825-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=example
[junit4] 2> 2244651 INFO (coreLoadExecutor-11825-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema example/1.1 with uniqueid field id
[junit4] 2> 2244657 INFO (coreLoadExecutor-11825-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 2244657 INFO (coreLoadExecutor-11825-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408
[junit4] 2> 2244657 INFO (coreLoadExecutor-11825-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/control/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/control/cores/collection1/data/]
[junit4] 2> 2244658 INFO (coreLoadExecutor-11825-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=63.595703125, floorSegmentMB=1.3916015625, forceMergeDeletesPctAllowed=4.290231389902482, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=38.46114751103808
[junit4] 2> 2244718 INFO (coreLoadExecutor-11825-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2244718 INFO (coreLoadExecutor-11825-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2244720 INFO (coreLoadExecutor-11825-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2244720 INFO (coreLoadExecutor-11825-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2244721 INFO (coreLoadExecutor-11825-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=99.2158203125, floorSegmentMB=1.04296875, forceMergeDeletesPctAllowed=8.124498729580251, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6921850569745925, deletesPctAllowed=46.85915773492842
[junit4] 2> 2244721 INFO (coreLoadExecutor-11825-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@15cc08f[collection1] main]
[junit4] 2> 2244722 WARN (coreLoadExecutor-11825-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 2244722 INFO (coreLoadExecutor-11825-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2244722 INFO (coreLoadExecutor-11825-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct, classname=DirectSolrSpellChecker, field=a_s, minQueryLength=3}
[junit4] 2> 2244723 INFO (coreLoadExecutor-11825-thread-1) [ x:collection1 ] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/elevate.xml
[junit4] 2> 2244727 INFO (coreLoadExecutor-11825-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2244727 INFO (coreLoadExecutor-11825-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1661882558270406656
[junit4] 2> 2244728 INFO (searcherExecutor-11826-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2244734 INFO (searcherExecutor-11826-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@15cc08f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2244886 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 2244887 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2244887 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2244887 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 2244892 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2244892 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2244892 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2244893 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1df2885d{/api/kj,null,AVAILABLE}
[junit4] 2> 2244893 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5e8367ba{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:38505}
[junit4] 2> 2244893 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.Server Started @2244940ms
[junit4] 2> 2244893 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema11.xml, hostContext=/api/kj, solrconfig=solrconfig-collapseqparser.xml, hostPort=38505, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/shard0/cores}
[junit4] 2> 2244893 ERROR (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2244893 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2244893 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2244893 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 2244893 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr
[junit4] 2> 2244893 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-22T16:31:52.872704Z
[junit4] 2> 2244893 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/shard0/solr.xml
[junit4] 2> 2244898 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2245276 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2245277 WARN (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@78623180[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2245277 WARN (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@78623180[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2245281 WARN (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@81bc811[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2245281 WARN (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@81bc811[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2245283 WARN (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 2245297 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2245297 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2245316 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408
[junit4] 2> 2245329 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408
[junit4] 2> 2245329 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408
[junit4] 2> 2245331 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/shard0/cores
[junit4] 2> 2245331 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2245336 INFO (coreLoadExecutor-11839-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2245355 INFO (coreLoadExecutor-11839-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=example
[junit4] 2> 2245407 INFO (coreLoadExecutor-11839-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema example/1.1 with uniqueid field id
[junit4] 2> 2245414 INFO (coreLoadExecutor-11839-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 2245414 INFO (coreLoadExecutor-11839-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408
[junit4] 2> 2245414 INFO (coreLoadExecutor-11839-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/shard0/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/shard0/cores/collection1/data/]
[junit4] 2> 2245415 INFO (coreLoadExecutor-11839-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=63.595703125, floorSegmentMB=1.3916015625, forceMergeDeletesPctAllowed=4.290231389902482, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=38.46114751103808
[junit4] 2> 2245524 INFO (coreLoadExecutor-11839-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2245524 INFO (coreLoadExecutor-11839-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2245526 INFO (coreLoadExecutor-11839-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2245526 INFO (coreLoadExecutor-11839-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2245527 INFO (coreLoadExecutor-11839-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=99.2158203125, floorSegmentMB=1.04296875, forceMergeDeletesPctAllowed=8.124498729580251, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6921850569745925, deletesPctAllowed=46.85915773492842
[junit4] 2> 2245527 INFO (coreLoadExecutor-11839-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@8b7b91c[collection1] main]
[junit4] 2> 2245527 WARN (coreLoadExecutor-11839-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 2245528 INFO (coreLoadExecutor-11839-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2245528 INFO (coreLoadExecutor-11839-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct, classname=DirectSolrSpellChecker, field=a_s, minQueryLength=3}
[junit4] 2> 2245528 INFO (coreLoadExecutor-11839-thread-1) [ x:collection1 ] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/elevate.xml
[junit4] 2> 2245532 INFO (coreLoadExecutor-11839-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2245533 INFO (coreLoadExecutor-11839-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1661882559115558912
[junit4] 2> 2245534 INFO (searcherExecutor-11840-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2245535 INFO (searcherExecutor-11840-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@8b7b91c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2245688 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/shard1/cores/collection1
[junit4] 2> 2245689 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2245689 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2245689 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 2245696 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2245696 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2245696 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2245696 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@57db4ab{/api/kj,null,AVAILABLE}
[junit4] 2> 2245697 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5c284d75{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:46203}
[junit4] 2> 2245697 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.Server Started @2245744ms
[junit4] 2> 2245697 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema11.xml, hostContext=/api/kj, solrconfig=solrconfig-collapseqparser.xml, hostPort=46203, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/shard1/cores}
[junit4] 2> 2245697 ERROR (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2245697 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2245697 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2245697 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 2245697 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr
[junit4] 2> 2245697 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-22T16:31:53.676458Z
[junit4] 2> 2245697 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/shard1/solr.xml
[junit4] 2> 2245701 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2246240 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2246241 WARN (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@37176e8e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2246241 WARN (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@37176e8e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2246244 WARN (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@e2622d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2246244 WARN (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@e2622d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2246245 WARN (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 2246260 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2246260 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2246281 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408
[junit4] 2> 2246292 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408
[junit4] 2> 2246292 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408
[junit4] 2> 2246294 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/shard1/cores
[junit4] 2> 2246294 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2246300 INFO (coreLoadExecutor-11853-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2246317 INFO (coreLoadExecutor-11853-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=example
[junit4] 2> 2246380 INFO (coreLoadExecutor-11853-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema example/1.1 with uniqueid field id
[junit4] 2> 2246385 INFO (coreLoadExecutor-11853-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 2246385 INFO (coreLoadExecutor-11853-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408
[junit4] 2> 2246385 INFO (coreLoadExecutor-11853-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/shard1/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/shard1/cores/collection1/data/]
[junit4] 2> 2246387 INFO (coreLoadExecutor-11853-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=63.595703125, floorSegmentMB=1.3916015625, forceMergeDeletesPctAllowed=4.290231389902482, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=38.46114751103808
[junit4] 2> 2246436 INFO (coreLoadExecutor-11853-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2246436 INFO (coreLoadExecutor-11853-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2246438 INFO (coreLoadExecutor-11853-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2246438 INFO (coreLoadExecutor-11853-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2246439 INFO (coreLoadExecutor-11853-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=99.2158203125, floorSegmentMB=1.04296875, forceMergeDeletesPctAllowed=8.124498729580251, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6921850569745925, deletesPctAllowed=46.85915773492842
[junit4] 2> 2246439 INFO (coreLoadExecutor-11853-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@65f8372e[collection1] main]
[junit4] 2> 2246439 WARN (coreLoadExecutor-11853-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 2246440 INFO (coreLoadExecutor-11853-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2246440 INFO (coreLoadExecutor-11853-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct, classname=DirectSolrSpellChecker, field=a_s, minQueryLength=3}
[junit4] 2> 2246440 INFO (coreLoadExecutor-11853-thread-1) [ x:collection1 ] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/elevate.xml
[junit4] 2> 2246443 INFO (coreLoadExecutor-11853-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2246444 INFO (coreLoadExecutor-11853-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1661882560070811648
[junit4] 2> 2246445 INFO (searcherExecutor-11854-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2246447 INFO (searcherExecutor-11854-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@65f8372e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2246593 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/shard2/cores/collection1
[junit4] 2> 2246594 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2246594 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2246594 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 2246602 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2246602 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2246602 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2246602 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2d356c5b{/api/kj,null,AVAILABLE}
[junit4] 2> 2246603 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@270d67ca{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:44983}
[junit4] 2> 2246603 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.s.Server Started @2246650ms
[junit4] 2> 2246603 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema11.xml, hostContext=/api/kj, solrconfig=solrconfig-collapseqparser.xml, hostPort=44983, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/shard2/cores}
[junit4] 2> 2246603 ERROR (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2246603 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2246603 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2246603 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 2246603 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr
[junit4] 2> 2246603 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-22T16:31:54.582607Z
[junit4] 2> 2246603 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/shard2/solr.xml
[junit4] 2> 2246607 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2247435 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2247436 WARN (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@557fa8d9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2247436 WARN (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@557fa8d9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2247439 WARN (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4189765e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2247439 WARN (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4189765e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2247440 WARN (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 2247461 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2247461 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2247483 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408
[junit4] 2> 2247494 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408
[junit4] 2> 2247494 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408
[junit4] 2> 2247496 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/shard2/cores
[junit4] 2> 2247496 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2247503 INFO (coreLoadExecutor-11867-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2247521 INFO (coreLoadExecutor-11867-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=example
[junit4] 2> 2247576 INFO (coreLoadExecutor-11867-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema example/1.1 with uniqueid field id
[junit4] 2> 2247582 INFO (coreLoadExecutor-11867-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 2247583 INFO (coreLoadExecutor-11867-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@65a4f408
[junit4] 2> 2247583 INFO (coreLoadExecutor-11867-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/shard2/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001/tempDir-001/shard2/cores/collection1/data/]
[junit4] 2> 2247584 INFO (coreLoadExecutor-11867-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=63.595703125, floorSegmentMB=1.3916015625, forceMergeDeletesPctAllowed=4.290231389902482, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=38.46114751103808
[junit4] 2> 2247646 INFO (coreLoadExecutor-11867-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2247646 INFO (coreLoadExecutor-11867-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2247647 INFO (coreLoadExecutor-11867-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2247647 INFO (coreLoadExecutor-11867-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2247648 INFO (coreLoadExecutor-11867-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=99.2158203125, floorSegmentMB=1.04296875, forceMergeDeletesPctAllowed=8.124498729580251, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6921850569745925, deletesPctAllowed=46.85915773492842
[junit4] 2> 2247649 INFO (coreLoadExecutor-11867-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3ff28c95[collection1] main]
[junit4] 2> 2247649 WARN (coreLoadExecutor-11867-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 2247650 INFO (coreLoadExecutor-11867-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2247650 INFO (coreLoadExecutor-11867-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct, classname=DirectSolrSpellChecker, field=a_s, minQueryLength=3}
[junit4] 2> 2247650 INFO (coreLoadExecutor-11867-thread-1) [ x:collection1 ] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/elevate.xml
[junit4] 2> 2247653 INFO (coreLoadExecutor-11867-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2247653 INFO (coreLoadExecutor-11867-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1661882561338540032
[junit4] 2> 2247655 INFO (searcherExecutor-11868-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2247656 INFO (searcherExecutor-11868-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3ff28c95[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2247657 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 2247660 INFO (TEST-DistributedExpandComponentTest.test-seed#[23E85FDA59314BCC]) [ ] o.a.s.SolrTestCaseJ4 ###Ending test
[junit4] 2> 2247668 INFO (closeThreadPool-13269-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1277401773
[junit4] 2> 2247673 INFO (coreCloseExecutor-11873-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1ae7914d
[junit4] 2> 2247673 INFO (coreCloseExecutor-11873-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=SolrCore@1ae7914d
[junit4] 2> 2247673 INFO (coreCloseExecutor-11873-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5c03e136: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@343dea2
[junit4] 2> 2247675 INFO (closeThreadPool-13269-thread-3) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=127944306
[junit4] 2> 2247726 INFO (closeThreadPool-13269-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=852134268
[junit4] 2> 2247726 INFO (closeThreadPool-13269-thread-4) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1226907966
[junit4] 2> 2247728 INFO (coreCloseExecutor-11873-thread-1) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 2247732 INFO (coreCloseExecutor-11874-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@38fb5815
[junit4] 2> 2247732 INFO (coreCloseExecutor-11874-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=SolrCore@38fb5815
[junit4] 2> 2247732 INFO (coreCloseExecutor-11874-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3a8d6bee: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@3f3585b
[junit4] 2> 2247744 INFO (closeThreadPool-13269-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2247744 INFO (closeThreadPool-13269-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1832a3fb: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@4f76b618
[junit4] 2> 2247744 INFO (coreCloseExecutor-11875-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6f11e07f
[junit4] 2> 2247744 INFO (coreCloseExecutor-11875-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=SolrCore@6f11e07f
[junit4] 2> 2247744 INFO (coreCloseExecutor-11875-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6f4c18be: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@3ca43e1a
[junit4] 2> 2247746 INFO (coreCloseExecutor-11874-thread-1) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 2247748 INFO (closeThreadPool-13269-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2247748 INFO (closeThreadPool-13269-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@731fcbe1: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@49c40b1
[junit4] 2> 2247750 INFO (closeThreadPool-13269-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2247750 INFO (closeThreadPool-13269-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@509aba0f: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@784535cb
[junit4] 2> 2247750 INFO (coreCloseExecutor-11876-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7bf37e86
[junit4] 2> 2247750 INFO (coreCloseExecutor-11876-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=SolrCore@7bf37e86
[junit4] 2> 2247751 INFO (coreCloseExecutor-11876-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7bbbc776: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@4a1ba4a3
[junit4] 2> 2247760 INFO (closeThreadPool-13269-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2247760 INFO (closeThreadPool-13269-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@f4a667b: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@10c1a0f4
[junit4] 2> 2247761 INFO (closeThreadPool-13269-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@503cd7be{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 2247764 INFO (closeThreadPool-13269-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@28246ba9{/api/kj,null,UNAVAILABLE}
[junit4] 2> 2247765 INFO (closeThreadPool-13269-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2247766 INFO (closeThreadPool-13269-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2247766 INFO (closeThreadPool-13269-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2cee0e99: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@49c40b1
[junit4] 2> 2247767 INFO (closeThreadPool-13269-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2247767 INFO (closeThreadPool-13269-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1f783b0a: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@784535cb
[junit4] 2> 2247769 INFO (closeThreadPool-13269-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5c284d75{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 2247769 INFO (closeThreadPool-13269-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@57db4ab{/api/kj,null,UNAVAILABLE}
[junit4] 2> 2247770 INFO (closeThreadPool-13269-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2247774 INFO (coreCloseExecutor-11876-thread-1) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 2247776 INFO (closeThreadPool-13269-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2247776 INFO (closeThreadPool-13269-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@328ebb2e: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@455a409
[junit4] 2> 2247780 INFO (closeThreadPool-13269-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2247780 INFO (closeThreadPool-13269-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3c1853ec: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@49c40b1
[junit4] 2> 2247781 INFO (coreCloseExecutor-11875-thread-1) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 2247782 INFO (closeThreadPool-13269-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2247782 INFO (closeThreadPool-13269-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@15165eef: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@784535cb
[junit4] 2> 2247784 INFO (closeThreadPool-13269-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2247784 INFO (closeThreadPool-13269-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2dd6a794: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5e08a07f
[junit4] 2> 2247792 INFO (closeThreadPool-13269-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5e8367ba{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 2247793 INFO (closeThreadPool-13269-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1df2885d{/api/kj,null,UNAVAILABLE}
[junit4] 2> 2247793 INFO (closeThreadPool-13269-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2247794 INFO (closeThreadPool-13269-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2247794 INFO (closeThreadPool-13269-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@10955796: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@49c40b1
[junit4] 2> 2247796 INFO (closeThreadPool-13269-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2247796 INFO (closeThreadPool-13269-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@463ca0b6: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@784535cb
[junit4] 2> 2247798 INFO (closeThreadPool-13269-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@270d67ca{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 2247798 INFO (closeThreadPool-13269-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2d356c5b{/api/kj,null,UNAVAILABLE}
[junit4] 2> 2247798 INFO (closeThreadPool-13269-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=DistributedExpandComponentTest -Dtests.method=test -Dtests.seed=23E85FDA59314BCC -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=sr-Latn-BA -Dtests.timezone=Atlantic/Bermuda -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 4.11s J0 | DistributedExpandComponentTest.test <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:45427/api/kj/collection1: Expected mime type application/octet-stream but got text/html. <html>
[junit4] > <head>
[junit4] > <meta http-equiv="Content-Type" content="text/html;charset=utf-8"/>
[junit4] > <title>Error 404 Not Found</title>
[junit4] > </head>
[junit4] > <body><h2>HTTP ERROR 404 Not Found</h2>
[junit4] > <table>
[junit4] > <tr><th>URI:</th><td>/solr/____v2/kj/collection1/update</td></tr>
[junit4] > <tr><th>STATUS:</th><td>404</td></tr>
[junit4] > <tr><th>MESSAGE:</th><td>Not Found</td></tr>
[junit4] > <tr><th>SERVLET:</th><td>-</td></tr>
[junit4] > </table>
[junit4] > <hr><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.4.24.v20191120</a><hr/>
[junit4] > </body>
[junit4] > </html>
[junit4] > at __randomizedtesting.SeedInfo.seed([23E85FDA59314BCC:ABBC6000F7CD2634]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:629)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
[junit4] > at org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:940)
[junit4] > at org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:903)
[junit4] > at org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:918)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.del(BaseDistributedSearchTestCase.java:609)
[junit4] > at org.apache.solr.handler.component.DistributedExpandComponentTest.test(DistributedExpandComponentTest.java:52)
[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:566)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] 2> 2247807 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 2247807 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2136834718
[junit4] 2> 2247807 INFO (coreCloseExecutor-11881-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@24c350ab
[junit4] 2> 2247807 INFO (coreCloseExecutor-11881-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=SolrCore@24c350ab
[junit4] 2> 2247807 INFO (coreCloseExecutor-11881-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5d1dd2f5: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@46b0de6e
[junit4] 2> 2247810 INFO (coreCloseExecutor-11881-thread-1) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 2247811 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2247811 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@607ea914: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@690e1834
[junit4] 2> 2247812 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2247812 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3cd64ce: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@49c40b1
[junit4] 2> 2247813 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2247813 INFO (SUITE-DistributedExpandComponentTest-seed#[23E85FDA59314BCC]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@71c2d918: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@784535cb
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedExpandComponentTest_23E85FDA59314BCC-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=1023, maxMBSortInHeap=5.5383394338759775, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@a7b6fa7), locale=sr-Latn-BA, timezone=Atlantic/Bermuda
[junit4] 2> NOTE: Linux 4.15.0-54-generic amd64/Oracle Corporation 11.0.4 (64-bit)/cpus=4,threads=1,free=248714272,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [BJQParserTest, TestCharFilters, SearchRateTriggerIntegrationTest, HighlighterWithoutStoredIdTest, TestInPlaceUpdatesRequiredField, TestPKIAuthenticationPlugin, FullSolrCloudDistribCmdsTest, TestLockTree, ShardSplitTest, SolrMetricReporterTest, TestNestedUpdateProcessor, TestCloudRecovery, TestSolrCoreProperties, CloudMLTQParserTest, SolrCoreCheckLockOnStartupTest, ReplicationFactorTest, TestUtilizeNode, BigEndianAscendingWordSerializerTest, TestZkChroot, TestCrossCoreJoin, TestLocalFSCloudBackupRestore, DistributedFacetSimpleRefinementLongTailTest, MigrateRouteKeyTest, TestManagedSynonymFilterFactory, ZkStateReaderTest, TestTrie, TestBulkSchemaConcurrent, TestSchemaResource, TestSuggestSpellingConverter, OverseerModifyCollectionTest, CustomHighlightComponentTest, AnalysisAfterCoreReloadTest, TestLegacyNumericRangeQueryBuilder, AutoAddReplicasIntegrationTest, DeleteNodeTest, TestBulkSchemaAPI, TestCollationField, AdminHandlersProxyTest, DocumentAnalysisRequestHandlerTest, CoreAdminHandlerTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestDistribPackageStore, TestMacros, TestUtils, FullHLLTest, ZkControllerTest, TestLeaderElectionWithEmptyReplica, TestConfigSets, ActionThrottleTest, TestCloudPhrasesIdentificationComponent, AuditLoggerPluginTest, ClusterStateTest, BadIndexSchemaTest, CollectionStateFormat2Test, TestRecoveryHdfs, TestReRankQParserPlugin, TestFieldTypeCollectionResource, TimeRoutedAliasUpdateProcessorTest, TestPolicyCloud, TestSolrCloudWithKerberosAlt, TestSolrConfigHandler, HighlighterConfigTest, NotRequiredUniqueKeyTest, SpellCheckComponentTest, TestStressThreadBackup, SpatialRPTFieldTypeTest, TestExportWriter, MBeansHandlerTest, DistributedSpellCheckComponentTest, TestSimPolicyCloud, OverseerTest, TestDynamicFieldNamesIndexCorrectly, TestTextField, SolrRequestParserTest, CursorPagingTest, TestFileDictionaryLookup, TestNumericTokenStream, UUIDFieldTest, TestMissingGroups, XMLAtomicUpdateMultivalueTest, ChaosMonkeySafeLeaderTest, TestFieldCache, SliceStateTest, TestCloudConsistency, BasicAuthStandaloneTest, TestExclusionRuleCollectionAccess, MetricsConfigTest, FileBasedSpellCheckerTest, AssignBackwardCompatibilityTest, ShowFileRequestHandlerTest, TestGroupingSearch, TestNRTOpen, TestSimLargeCluster, TestPHPSerializedResponseWriter, AddSchemaFieldsUpdateProcessorFactoryTest, TestDistributedMissingSort, TestHighFrequencyDictionaryFactory, TestConfigReload, TestCollectionAPI, TestDynamicLoading, ClassificationUpdateProcessorIntegrationTest, RecoveryAfterSoftCommitTest, TestConfigSetsAPIZkFailure, SubstringBytesRefFilterTest, LargeFieldTest, TermsComponentTest, TestEmbeddedSolrServerSchemaAPI, TriggerIntegrationTest, PluginInfoTest, DOMUtilTest, TestChildDocTransformer, HttpSolrCallGetCoreTest, TestSchemaManager, SchemaApiFailureTest, TestCollectionAPIs, ShardRoutingCustomTest, ResponseLogComponentTest, DistributedFacetPivotSmallTest, TestDefaultStatsCache, ZookeeperStatusHandlerTest, CachingDirectoryFactoryTest, DocumentBuilderTest, SystemCollectionCompatTest, TestImplicitCoreProperties, TestJmxIntegration, SortByFunctionTest, TestCorePropertiesReload, TestPerFieldSimilarityWithDefaultOverride, TestDistribDocBasedVersion, TestMultiValuedNumericRangeQuery, DistributedFacetPivotLargeTest, TestSQLHandlerNonCloud, TestReloadAndDeleteDocs, DistributedQueryComponentCustomSortTest, TestExactStatsCache, TestOverriddenPrefixQueryForCustomFieldType, TaggingAttributeTest, CdcrUpdateLogTest, HttpPartitionTest, PKIAuthenticationIntegrationTest, DocExpirationUpdateProcessorFactoryTest, TestSimComputePlanAction, TestSSLRandomization, HdfsNNFailoverTest, TestReplicationHandler, TestShortCircuitedRequests, TestSizeLimitedDistributedMap, TestSkipOverseerOperations, TestStressLiveNodes, ConcurrentDeleteAndCreateCollectionTest, CustomCollectionTest, TestHdfsCloudBackupRestore, TestRequestStatusCollectionAPI, HdfsAutoAddReplicasIntegrationTest, HttpTriggerListenerTest, IndexSizeTriggerMixedBoundsTest, IndexSizeTriggerTest, TestSimClusterStateProvider, TestSimTriggerIntegration, BaseCdcrDistributedZkTest, CdcrBidirectionalTest, CdcrBootstrapTest, RAMDirectoryFactoryTest, RequestHandlersTest, TestBackupRepositoryFactory, TestCodecSupport, TestConfig, TestConfigSetImmutable, TestDirectoryFactory, TestSolrIndexConfig, TestSolrXml, TestSolrCloudSnapshots, TestRestoreCore, TestSolrConfigHandlerConcurrent, CoreAdminCreateDiscoverTest, CoreMergeIndexesAdminHandlerTest, DaemonStreamApiTest, LoggingHandlerTest, SecurityConfHandlerTest, StatsReloadRaceTest, DistributedExpandComponentTest]
[junit4] Completed [652/905 (1!)] on J0 in 5.69s, 1 test, 1 error <<< FAILURES!
[...truncated 53439 lines...]