Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.10/270/
1 tests failed. FAILED: org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.test Error Message: Error from server at http://127.0.0.1:39295/_gb/fd/collection1: org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded:[http://[::1]:6/_gb/fd, http://[::1]:8/_gb/fd, http://[::1]:4/_gb/fd] Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:39295/_gb/fd/collection1: org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded:[http://[::1]:6/_gb/fd, http://[::1]:8/_gb/fd, http://[::1]:4/_gb/fd] at __randomizedtesting.SeedInfo.seed([3FF47175BE45C446:B7A04EAF10B9A9BE]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:681) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:214) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1003) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1018) at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:623) at org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.queryAllowPartialResults(DistributedQueryComponentCustomSortTest.java:139) at org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.test(DistributedQueryComponentCustomSortTest.java:129) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1084) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1055) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.junit.rules.RunRules.evaluate(RunRules.java:20) 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 org.junit.rules.RunRules.evaluate(RunRules.java:20) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.junit.rules.RunRules.evaluate(RunRules.java:20) 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 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 org.junit.rules.RunRules.evaluate(RunRules.java:20) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 13499 lines...] [junit4] Suite: org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest [junit4] 2> 112693 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/server/solr/configsets/_default/conf' [junit4] 2> 112693 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/data-dir-11-001 [junit4] 2> 112693 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=21 numCloses=21 [junit4] 2> 112693 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 112695 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 112695 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 112695 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_gb/fd [junit4] 2> 112696 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 112703 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 112718 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.2 [junit4] 2> 112718 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 112719 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead [junit4] 2> 112720 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 112721 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 113158 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.c.CoreContainer Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/userfiles]. Features requiring this directory may fail. [junit4] 2> => java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/userfiles" "write") [junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) [junit4] 2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/userfiles" "write") [junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:1.8.0_291] [junit4] 2> at java.security.AccessController.checkPermission(AccessController.java:886) ~[?:1.8.0_291] [junit4] 2> at java.lang.SecurityManager.checkPermission(SecurityManager.java:549) ~[?:1.8.0_291] [junit4] 2> at java.lang.SecurityManager.checkWrite(SecurityManager.java:979) ~[?:1.8.0_291] [junit4] 2> at sun.nio.fs.UnixPath.checkWrite(UnixPath.java:801) ~[?:?] [junit4] 2> at sun.nio.fs.UnixFileSystemProvider.createDirectory(UnixFileSystemProvider.java:376) ~[?:?] [junit4] 2> at java.nio.file.Files.createDirectory(Files.java:674) ~[?:1.8.0_291] [junit4] 2> at java.nio.file.Files.createAndCheckIsDirectory(Files.java:781) ~[?:1.8.0_291] [junit4] 2> at java.nio.file.Files.createDirectories(Files.java:727) ~[?:1.8.0_291] [junit4] 2> at org.apache.solr.core.CoreContainer.<init>(CoreContainer.java:378) [java/:?] [junit4] 2> at org.apache.solr.core.CoreContainer.<init>(CoreContainer.java:343) [java/:?] [junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:183) [java/:?] [junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:156) [java/:?] [junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:162) [java/:?] [junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) [java/:?] [junit4] 2> at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:813) [java/:?] [junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:803) [java/:?] [junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:664) [java/:?] [junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:653) [java/:?] [junit4] 2> at org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.setUpBeforeClass(DistributedQueryComponentCustomSortTest.java:44) [test/:?] [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_291] [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_291] [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_291] [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_291] [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) [randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:878) [randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) [randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.1.jar:4.13.1] [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.2.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.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.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 org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.1.jar:4.13.1] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) [randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_291] [junit4] 2> 113159 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.f.DistribPackageStore Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/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/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/filestore" "write") [junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) [junit4] 2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/filestore" "write") [junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:1.8.0_291] [junit4] 2> at java.security.AccessController.checkPermission(AccessController.java:886) ~[?:1.8.0_291] [junit4] 2> at java.lang.SecurityManager.checkPermission(SecurityManager.java:549) ~[?:1.8.0_291] [junit4] 2> at java.lang.SecurityManager.checkWrite(SecurityManager.java:979) ~[?:1.8.0_291] [junit4] 2> at java.io.File.mkdir(File.java:1348) ~[?:1.8.0_291] [junit4] 2> at java.io.File.mkdirs(File.java:1380) ~[?:1.8.0_291] [junit4] 2> at org.apache.solr.filestore.DistribPackageStore.ensurePackageStoreDir(DistribPackageStore.java:561) [java/:?] [junit4] 2> at org.apache.solr.filestore.DistribPackageStore.<init>(DistribPackageStore.java:81) [java/:?] [junit4] 2> at org.apache.solr.filestore.PackageStoreAPI.<init>(PackageStoreAPI.java:78) [java/:?] [junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:679) [java/:?] [junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:184) [java/:?] [junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:156) [java/:?] [junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:162) [java/:?] [junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) [java/:?] [junit4] 2> at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:813) [java/:?] [junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:803) [java/:?] [junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:664) [java/:?] [junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:653) [java/:?] [junit4] 2> at org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.setUpBeforeClass(DistributedQueryComponentCustomSortTest.java:44) [test/:?] [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_291] [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_291] [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_291] [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_291] [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) [randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:878) [randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) [randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.1.jar:4.13.1] [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.2.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.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.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 org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.1.jar:4.13.1] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) [randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_291] [junit4] 2> 113161 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 113162 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6b7ca339[provider=null,keyStore=null,trustStore=null] [junit4] 2> 113162 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6b7ca339[provider=null,keyStore=null,trustStore=null] [junit4] 2> 113165 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7c52a4b2[provider=null,keyStore=null,trustStore=null] [junit4] 2> 113166 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7c52a4b2[provider=null,keyStore=null,trustStore=null] [junit4] 2> 113167 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-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> 113193 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ebf5b8b [junit4] 2> 113199 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ebf5b8b [junit4] 2> 113199 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ebf5b8b [junit4] 2> 113209 INFO (coreLoadExecutor-810-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024 [junit4] 2> 113217 INFO (coreLoadExecutor-810-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 113232 INFO (coreLoadExecutor-810-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.2 [junit4] 2> 113232 WARN (coreLoadExecutor-810-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 113232 WARN (coreLoadExecutor-810-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead [junit4] 2> 113234 INFO (coreLoadExecutor-810-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 113234 INFO (coreLoadExecutor-810-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 113234 INFO (coreLoadExecutor-810-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 113234 INFO (coreLoadExecutor-810-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@1ebf5b8b [junit4] 2> 113235 INFO (coreLoadExecutor-810-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/data-dir-11-001/] [junit4] 2> 113236 INFO (coreLoadExecutor-810-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=55.345703125, floorSegmentMB=1.748046875, forceMergeDeletesPctAllowed=11.181366525983975, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8520364777362123, deletesPctAllowed=26.587945617618235 [junit4] 2> 113283 INFO (coreLoadExecutor-810-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 113284 INFO (coreLoadExecutor-810-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 113284 INFO (coreLoadExecutor-810-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 113284 INFO (coreLoadExecutor-810-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 113285 INFO (coreLoadExecutor-810-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=14, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8151730797736637] [junit4] 2> 113286 INFO (coreLoadExecutor-810-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 113297 INFO (coreLoadExecutor-810-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 113322 INFO (coreLoadExecutor-810-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 113323 INFO (coreLoadExecutor-810-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1714571953655775232 [junit4] 2> 113324 INFO (searcherExecutor-812-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 113325 INFO (searcherExecutor-812-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 113325 INFO (searcherExecutor-812-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 113325 INFO (searcherExecutor-812-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 113325 INFO (searcherExecutor-812-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 113325 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 113326 INFO (searcherExecutor-812-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 113326 INFO (searcherExecutor-812-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 113326 INFO (searcherExecutor-812-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 113326 INFO (searcherExecutor-812-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 113328 INFO (searcherExecutor-812-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms [junit4] 2> 113447 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/control/cores/collection1 [junit4] 2> 113453 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 113453 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 113453 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10 [junit4] 2> 113473 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 113473 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 113473 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 113485 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@a17989c{/_gb/fd,null,AVAILABLE} [junit4] 2> 113486 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1b486e63{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:33885} [junit4] 2> 113486 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.Server Started @113512ms [junit4] 2> 113486 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/_gb/fd, hostPort=33885, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/control/cores} [junit4] 2> 113486 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 113486 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 113486 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.10.2 [junit4] 2> 113486 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 113486 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr [junit4] 2> 113486 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-10-25T06:27:15.995Z [junit4] 2> 113486 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/control/solr.xml [junit4] 2> 113489 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 113489 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 113491 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1ebf5b8b, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 114088 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 114092 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@19830e92[provider=null,keyStore=null,trustStore=null] [junit4] 2> 114092 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@19830e92[provider=null,keyStore=null,trustStore=null] [junit4] 2> 114096 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2abb9632[provider=null,keyStore=null,trustStore=null] [junit4] 2> 114096 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2abb9632[provider=null,keyStore=null,trustStore=null] [junit4] 2> 114097 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] 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> 114110 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4 [junit4] 2> 114110 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 114135 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ebf5b8b [junit4] 2> 114147 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ebf5b8b [junit4] 2> 114147 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ebf5b8b [junit4] 2> 114148 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/control/cores [junit4] 2> 114148 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 114177 INFO (coreLoadExecutor-830-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 114192 INFO (coreLoadExecutor-830-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.2 [junit4] 2> 114192 WARN (coreLoadExecutor-830-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 114193 WARN (coreLoadExecutor-830-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead [junit4] 2> 114194 INFO (coreLoadExecutor-830-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 114195 INFO (coreLoadExecutor-830-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 114195 INFO (coreLoadExecutor-830-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 114195 INFO (coreLoadExecutor-830-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@1ebf5b8b [junit4] 2> 114195 INFO (coreLoadExecutor-830-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/control/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/control/cores/collection1/data/] [junit4] 2> 114197 INFO (coreLoadExecutor-830-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=55.345703125, floorSegmentMB=1.748046875, forceMergeDeletesPctAllowed=11.181366525983975, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8520364777362123, deletesPctAllowed=26.587945617618235 [junit4] 2> 114248 INFO (coreLoadExecutor-830-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 114248 INFO (coreLoadExecutor-830-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 114249 INFO (coreLoadExecutor-830-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 114249 INFO (coreLoadExecutor-830-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 114250 INFO (coreLoadExecutor-830-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=14, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8151730797736637] [junit4] 2> 114251 INFO (coreLoadExecutor-830-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 114262 INFO (coreLoadExecutor-830-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 114325 INFO (coreLoadExecutor-830-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 114325 INFO (coreLoadExecutor-830-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1714571954706448384 [junit4] 2> 114326 INFO (searcherExecutor-832-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 114326 INFO (searcherExecutor-832-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 114326 INFO (searcherExecutor-832-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 114326 INFO (searcherExecutor-832-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 114327 INFO (searcherExecutor-832-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 114327 INFO (searcherExecutor-832-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 114327 INFO (searcherExecutor-832-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 114327 INFO (searcherExecutor-832-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 114327 INFO (searcherExecutor-832-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 114330 INFO (searcherExecutor-832-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms [junit4] 2> 114430 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/shard0/cores/collection1 [junit4] 2> 114432 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 114432 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 114432 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10 [junit4] 2> 114433 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 114433 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 114433 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 114437 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@705031f6{/_gb/fd,null,AVAILABLE} [junit4] 2> 114438 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@61c378d{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:39295} [junit4] 2> 114438 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.Server Started @114464ms [junit4] 2> 114438 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/_gb/fd, hostPort=39295, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/shard0/cores} [junit4] 2> 114438 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 114438 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 114438 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.10.2 [junit4] 2> 114438 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 114438 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr [junit4] 2> 114438 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-10-25T06:27:16.947Z [junit4] 2> 114438 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/shard0/solr.xml [junit4] 2> 114441 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 114441 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 114443 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1ebf5b8b, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 114605 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 114606 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4c180560[provider=null,keyStore=null,trustStore=null] [junit4] 2> 114606 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4c180560[provider=null,keyStore=null,trustStore=null] [junit4] 2> 114611 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5407a3c[provider=null,keyStore=null,trustStore=null] [junit4] 2> 114611 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5407a3c[provider=null,keyStore=null,trustStore=null] [junit4] 2> 114612 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] 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> 114622 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4 [junit4] 2> 114622 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 114642 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ebf5b8b [junit4] 2> 114679 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ebf5b8b [junit4] 2> 114679 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ebf5b8b [junit4] 2> 114681 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/shard0/cores [junit4] 2> 114681 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 114695 INFO (coreLoadExecutor-849-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 114715 INFO (coreLoadExecutor-849-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.2 [junit4] 2> 114716 WARN (coreLoadExecutor-849-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 114716 WARN (coreLoadExecutor-849-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead [junit4] 2> 114717 INFO (coreLoadExecutor-849-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 114718 INFO (coreLoadExecutor-849-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 114718 INFO (coreLoadExecutor-849-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 114718 INFO (coreLoadExecutor-849-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@1ebf5b8b [junit4] 2> 114718 INFO (coreLoadExecutor-849-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/shard0/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/shard0/cores/collection1/data/] [junit4] 2> 114720 INFO (coreLoadExecutor-849-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=55.345703125, floorSegmentMB=1.748046875, forceMergeDeletesPctAllowed=11.181366525983975, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8520364777362123, deletesPctAllowed=26.587945617618235 [junit4] 2> 114774 INFO (coreLoadExecutor-849-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 114774 INFO (coreLoadExecutor-849-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 114776 INFO (coreLoadExecutor-849-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 114776 INFO (coreLoadExecutor-849-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 114776 INFO (coreLoadExecutor-849-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=14, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8151730797736637] [junit4] 2> 114778 INFO (coreLoadExecutor-849-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 114789 INFO (coreLoadExecutor-849-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 114830 INFO (coreLoadExecutor-849-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 114831 INFO (coreLoadExecutor-849-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1714571955237027840 [junit4] 2> 114832 INFO (searcherExecutor-851-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 114832 INFO (searcherExecutor-851-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 114832 INFO (searcherExecutor-851-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 114832 INFO (searcherExecutor-851-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 114832 INFO (searcherExecutor-851-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 114832 INFO (searcherExecutor-851-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 114833 INFO (searcherExecutor-851-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 114833 INFO (searcherExecutor-851-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 114833 INFO (searcherExecutor-851-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 114835 INFO (searcherExecutor-851-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms [junit4] 2> 115468 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/shard1/cores/collection1 [junit4] 2> 115469 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 115469 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 115469 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10 [junit4] 2> 115472 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 115472 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 115472 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 115474 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1fd117d5{/_gb/fd,null,AVAILABLE} [junit4] 2> 115475 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@55657ff8{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:35267} [junit4] 2> 115475 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.Server Started @115501ms [junit4] 2> 115475 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/_gb/fd, hostPort=35267, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/shard1/cores} [junit4] 2> 115475 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 115475 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 115475 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.10.2 [junit4] 2> 115475 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 115475 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr [junit4] 2> 115475 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-10-25T06:27:17.984Z [junit4] 2> 115475 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/shard1/solr.xml [junit4] 2> 115486 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 115486 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 115487 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1ebf5b8b, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 115883 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 115885 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@42e661d9[provider=null,keyStore=null,trustStore=null] [junit4] 2> 115886 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@42e661d9[provider=null,keyStore=null,trustStore=null] [junit4] 2> 115889 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5c582ffe[provider=null,keyStore=null,trustStore=null] [junit4] 2> 115889 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5c582ffe[provider=null,keyStore=null,trustStore=null] [junit4] 2> 115890 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] 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> 115914 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4 [junit4] 2> 115914 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 116176 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ebf5b8b [junit4] 2> 116188 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ebf5b8b [junit4] 2> 116188 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ebf5b8b [junit4] 2> 116189 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/shard1/cores [junit4] 2> 116189 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 116201 INFO (coreLoadExecutor-868-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 116222 INFO (coreLoadExecutor-868-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.2 [junit4] 2> 116223 WARN (coreLoadExecutor-868-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 116223 WARN (coreLoadExecutor-868-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead [junit4] 2> 116225 INFO (coreLoadExecutor-868-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 116226 INFO (coreLoadExecutor-868-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 116226 INFO (coreLoadExecutor-868-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 116229 INFO (coreLoadExecutor-868-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@1ebf5b8b [junit4] 2> 116230 INFO (coreLoadExecutor-868-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/shard1/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/shard1/cores/collection1/data/] [junit4] 2> 116232 INFO (coreLoadExecutor-868-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=55.345703125, floorSegmentMB=1.748046875, forceMergeDeletesPctAllowed=11.181366525983975, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8520364777362123, deletesPctAllowed=26.587945617618235 [junit4] 2> 116309 INFO (coreLoadExecutor-868-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 116309 INFO (coreLoadExecutor-868-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 116310 INFO (coreLoadExecutor-868-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 116310 INFO (coreLoadExecutor-868-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 116311 INFO (coreLoadExecutor-868-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=14, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8151730797736637] [junit4] 2> 116313 INFO (coreLoadExecutor-868-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 116320 INFO (coreLoadExecutor-868-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 116365 INFO (coreLoadExecutor-868-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 116366 INFO (coreLoadExecutor-868-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1714571956846592000 [junit4] 2> 116367 INFO (searcherExecutor-870-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 116367 INFO (searcherExecutor-870-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 116367 INFO (searcherExecutor-870-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 116367 INFO (searcherExecutor-870-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 116368 INFO (searcherExecutor-870-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 116368 INFO (searcherExecutor-870-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 116368 INFO (searcherExecutor-870-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 116368 INFO (searcherExecutor-870-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 116368 INFO (searcherExecutor-870-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 116371 INFO (searcherExecutor-870-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms [junit4] 2> 116983 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/shard2/cores/collection1 [junit4] 2> 116984 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 116984 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 116984 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10 [junit4] 2> 117144 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 117144 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 117144 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 117194 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@12462127{/_gb/fd,null,AVAILABLE} [junit4] 2> 117195 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6978ae6d{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:39769} [junit4] 2> 117195 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.s.Server Started @117221ms [junit4] 2> 117195 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/_gb/fd, hostPort=39769, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/shard2/cores} [junit4] 2> 117195 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 117195 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 117195 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.10.2 [junit4] 2> 117195 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 117195 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr [junit4] 2> 117195 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-10-25T06:27:19.704Z [junit4] 2> 117195 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/shard2/solr.xml [junit4] 2> 117237 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 117237 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 117239 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1ebf5b8b, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 118520 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 118521 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2d6e43a8[provider=null,keyStore=null,trustStore=null] [junit4] 2> 118521 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2d6e43a8[provider=null,keyStore=null,trustStore=null] [junit4] 2> 118568 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4898f6bd[provider=null,keyStore=null,trustStore=null] [junit4] 2> 118568 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4898f6bd[provider=null,keyStore=null,trustStore=null] [junit4] 2> 118570 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] 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> 118834 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4 [junit4] 2> 118834 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 118854 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ebf5b8b [junit4] 2> 118864 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ebf5b8b [junit4] 2> 118864 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ebf5b8b [junit4] 2> 118866 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/shard2/cores [junit4] 2> 118866 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FF47175BE45C446]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 118875 INFO (coreLoadExecutor-887-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 118892 INFO (coreLoadExecutor-887-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.2 [junit4] 2> 118892 WARN (coreLoadExecutor-887-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 118892 WARN (coreLoadExecutor-887-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead [junit4] 2> 118893 INFO (coreLoadExecutor-887-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 118894 INFO (coreLoadExecutor-887-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 118894 INFO (coreLoadExecutor-887-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 118894 INFO (coreLoadExecutor-887-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@1ebf5b8b [junit4] 2> 118894 INFO (coreLoadExecutor-887-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/shard2/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001/tempDir-001/shard2/cores/collection1/data/] [junit4] 2> 118896 INFO (coreLoadExecutor-887-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=55.345703125, floorSegmentMB=1.748046875, forceMergeDeletesPctAllowed=11.181366525983975, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8520364777362123, deletesPctAllowed=26.587945617618235 [junit4] 2> 119053 INFO (coreLoadExecutor-887-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 119053 INFO (coreLoadExecutor-887-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 119054 INFO (coreLoadExecutor-887-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 119054 INFO (coreLoadExecutor-887-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 119055 INFO (coreLoadExecutor-887-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=14, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8151730797736637] [junit4] 2> 119056 INFO (coreLoadExecutor-887-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 119065 INFO (coreLoadExecutor-887-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 119110 INFO (coreLoadExecutor-887-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 119110 INFO (coreLoadExecutor-887-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1714571959723884544 [junit4] 2> 119111 INFO (searcherExecutor-889-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 119112 INFO (searcherExecutor-889-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 119112 INFO (searcherExecutor-889-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 119112 INFO (searcherExecutor-889-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 119112 INFO (searcherExecutor-889-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 119112 INFO (searcherExecutor-889-thread-1-processing-x:collection1) [ x:collection1 ] o.a [...truncated too long message...] ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@121adf75: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@73623e71 [junit4] 2> 119832 INFO (closeThreadPool-897-thread-7) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null [junit4] 2> 119832 INFO (closeThreadPool-897-thread-7) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1ef957d1: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@7d69ef47 [junit4] 2> 119834 INFO (closeThreadPool-897-thread-6) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null [junit4] 2> 119834 INFO (closeThreadPool-897-thread-6) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4ec23b14: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@5273d502 [junit4] 2> 119839 INFO (closeThreadPool-897-thread-7) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null [junit4] 2> 119839 INFO (closeThreadPool-897-thread-7) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@381c5764: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@73623e71 [junit4] 2> 119841 INFO (closeThreadPool-897-thread-7) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null [junit4] 2> 119841 INFO (closeThreadPool-897-thread-7) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7469d9db: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@5273d502 [junit4] 2> 119844 INFO (closeThreadPool-897-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1b486e63{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0} [junit4] 2> 119845 INFO (closeThreadPool-897-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@a17989c{/_gb/fd,null,STOPPED} [junit4] 2> 119845 INFO (closeThreadPool-897-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 119854 INFO (closeThreadPool-897-thread-5) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@61c378d{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0} [junit4] 2> 119854 INFO (closeThreadPool-897-thread-5) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@705031f6{/_gb/fd,null,STOPPED} [junit4] 2> 119854 INFO (closeThreadPool-897-thread-5) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 119857 INFO (closeThreadPool-897-thread-6) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@55657ff8{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0} [junit4] 2> 119860 INFO (closeThreadPool-897-thread-6) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1fd117d5{/_gb/fd,null,STOPPED} [junit4] 2> 119860 INFO (closeThreadPool-897-thread-6) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 119861 INFO (closeThreadPool-897-thread-7) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6978ae6d{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0} [junit4] 2> 119861 INFO (closeThreadPool-897-thread-7) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@12462127{/_gb/fd,null,STOPPED} [junit4] 2> 119861 INFO (closeThreadPool-897-thread-7) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=DistributedQueryComponentCustomSortTest -Dtests.method=test -Dtests.seed=3FF47175BE45C446 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=ar-YE -Dtests.timezone=Africa/Douala -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 6.54s J1 | DistributedQueryComponentCustomSortTest.test <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:39295/_gb/fd/collection1: org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded:[http://[::1]:6/_gb/fd, http://[::1]:8/_gb/fd, http://[::1]:4/_gb/fd] [junit4] > at __randomizedtesting.SeedInfo.seed([3FF47175BE45C446:B7A04EAF10B9A9BE]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:681) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:214) [junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1003) [junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1018) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:623) [junit4] > at org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.queryAllowPartialResults(DistributedQueryComponentCustomSortTest.java:139) [junit4] > at org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.test(DistributedQueryComponentCustomSortTest.java:129) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1084) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1055) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] 2> 119867 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 119867 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=885525541 [junit4] 2> 119868 INFO (coreCloseExecutor-915-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@32eaed2e [junit4] 2> 119868 INFO (coreCloseExecutor-915-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@32eaed2e [junit4] 2> 119868 INFO (coreCloseExecutor-915-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5adaa728: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@3d0ef0d5 [junit4] 2> 119871 INFO (coreCloseExecutor-915-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary). [junit4] 2> 119872 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null [junit4] 2> 119872 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4c157037: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@2f8713f7 [junit4] 2> 119873 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null [junit4] 2> 119874 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1ee5bd1c: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@73623e71 [junit4] 2> 119874 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null [junit4] 2> 119874 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FF47175BE45C446]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2035b662: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@5273d502 [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FF47175BE45C446-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene87), sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=ar-YE, timezone=Africa/Douala [junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_291 (64-bit)/cpus=4,threads=2,free=102297464,total=350224384 [junit4] 2> NOTE: All tests run in this JVM: [CoreAdminHandlerTest, TestUniqueKeyFieldResource, MaxScoreCollectorTest, TestSimTriggerIntegration, TestSolrJacksonAnnotation, SolrMetricManagerTest, CircularListTest, TestTolerantUpdateProcessorCloud, ZkStateReaderTest, RestoreTriggerStateTest, LeaderTragicEventTest, TestUseDocValuesAsStored2, TestCircuitBreaker, DistributedQueryComponentCustomSortTest] [junit4] Completed [52/948 (1!)] on J1 in 7.42s, 1 test, 1 error <<< FAILURES! [...truncated 57305 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
