Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.10/87/
1 tests failed. FAILED: org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.test Error Message: Error from server at http://127.0.0.1:34437/_/l/collection1: org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded:[http://[::1]:8/_/l, http://[::1]:4/_/l, http://[::1]:6/_/l] Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:34437/_/l/collection1: org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded:[http://[::1]:8/_/l, http://[::1]:4/_/l, http://[::1]:6/_/l] at __randomizedtesting.SeedInfo.seed([4936F517EA232D4B:C162CACD44DF40B3]: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 15662 lines...] [junit4] Suite: org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest [junit4] 2> 2041228 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-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> 2041228 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/data-dir-173-001 [junit4] 2> 2041228 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=237 numCloses=237 [junit4] 2> 2041228 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 2041230 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-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> 2041230 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 2041230 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_/l [junit4] 2> 2041230 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 2041235 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-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> 2041249 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.0 [junit4] 2> 2041250 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 2041250 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead [junit4] 2> 2041251 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 2041251 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 2042109 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-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> 2042111 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-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> 2042112 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 2042113 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5bbf8815[provider=null,keyStore=null,trustStore=null] [junit4] 2> 2042113 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5bbf8815[provider=null,keyStore=null,trustStore=null] [junit4] 2> 2042115 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@767cd282[provider=null,keyStore=null,trustStore=null] [junit4] 2> 2042115 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@767cd282[provider=null,keyStore=null,trustStore=null] [junit4] 2> 2042116 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-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> 2042145 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a185bfb [junit4] 2> 2042152 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a185bfb [junit4] 2> 2042152 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a185bfb [junit4] 2> 2042159 INFO (coreLoadExecutor-22217-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024 [junit4] 2> 2042182 INFO (coreLoadExecutor-22217-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> 2042210 INFO (coreLoadExecutor-22217-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.0 [junit4] 2> 2042210 WARN (coreLoadExecutor-22217-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 2042211 WARN (coreLoadExecutor-22217-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> 2042212 INFO (coreLoadExecutor-22217-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 2042212 INFO (coreLoadExecutor-22217-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 2042212 INFO (coreLoadExecutor-22217-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> 2042212 INFO (coreLoadExecutor-22217-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@6a185bfb [junit4] 2> 2042212 INFO (coreLoadExecutor-22217-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/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/data-dir-173-001/] [junit4] 2> 2042218 INFO (coreLoadExecutor-22217-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=45, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 2042264 INFO (coreLoadExecutor-22217-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2042264 INFO (coreLoadExecutor-22217-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2042265 INFO (coreLoadExecutor-22217-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2042265 INFO (coreLoadExecutor-22217-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2042266 INFO (coreLoadExecutor-22217-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=56.26953125, floorSegmentMB=1.49609375, forceMergeDeletesPctAllowed=15.355064232255964, segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4392499891505728, deletesPctAllowed=38.20519038713086 [junit4] 2> 2042266 INFO (coreLoadExecutor-22217-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 2042558 INFO (coreLoadExecutor-22217-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 2042880 INFO (coreLoadExecutor-22217-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 2042880 INFO (coreLoadExecutor-22217-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1711587914191732736 [junit4] 2> 2042881 INFO (searcherExecutor-22219-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 2042882 INFO (searcherExecutor-22219-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 2042882 INFO (searcherExecutor-22219-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 2042882 INFO (searcherExecutor-22219-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 2042882 INFO (searcherExecutor-22219-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 2042882 INFO (searcherExecutor-22219-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 2042882 INFO (searcherExecutor-22219-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 2042882 INFO (searcherExecutor-22219-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 2042883 INFO (searcherExecutor-22219-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 2042883 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 2042884 INFO (searcherExecutor-22219-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms [junit4] 2> 2043276 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] 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/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/control/cores/collection1 [junit4] 2> 2043277 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 2043277 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 2043277 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.Server jetty-9.4.41.v20210516; built: 2021-05-16T23:56:28.993Z; git: 98607f93c7833e7dc59489b13f3cb0a114fb9f4c; jvm 1.8.0_291-b10 [junit4] 2> 2043282 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2043283 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2043283 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 2043287 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@775de43d{/_/l,null,AVAILABLE} [junit4] 2> 2043287 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3c112348{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:33297} [junit4] 2> 2043287 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.Server Started @2043348ms [junit4] 2> 2043287 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/_/l, hostPort=33297, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/control/cores} [junit4] 2> 2043287 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2043287 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 2043287 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.10.0 [junit4] 2> 2043287 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 2043287 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr [junit4] 2> 2043287 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-09-22T07:57:14.443Z [junit4] 2> 2043287 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] 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/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/control/solr.xml [junit4] 2> 2043290 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 2043290 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 2043291 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6a185bfb, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 2044768 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 2044769 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6abda673[provider=null,keyStore=null,trustStore=null] [junit4] 2> 2044769 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6abda673[provider=null,keyStore=null,trustStore=null] [junit4] 2> 2044772 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@a835a0[provider=null,keyStore=null,trustStore=null] [junit4] 2> 2044772 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@a835a0[provider=null,keyStore=null,trustStore=null] [junit4] 2> 2044773 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] 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> 2044783 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4 [junit4] 2> 2044783 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 2044802 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a185bfb [junit4] 2> 2044810 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a185bfb [junit4] 2> 2044810 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a185bfb [junit4] 2> 2044811 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] 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/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/control/cores [junit4] 2> 2044811 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 2044824 INFO (coreLoadExecutor-22237-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> 2044840 INFO (coreLoadExecutor-22237-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.0 [junit4] 2> 2044840 WARN (coreLoadExecutor-22237-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 2044841 WARN (coreLoadExecutor-22237-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> 2044842 INFO (coreLoadExecutor-22237-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 2044843 INFO (coreLoadExecutor-22237-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 2044843 INFO (coreLoadExecutor-22237-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> 2044843 INFO (coreLoadExecutor-22237-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@6a185bfb [junit4] 2> 2044843 INFO (coreLoadExecutor-22237-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/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/control/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/control/cores/collection1/data/] [junit4] 2> 2044844 INFO (coreLoadExecutor-22237-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=45, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 2044911 INFO (coreLoadExecutor-22237-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2044911 INFO (coreLoadExecutor-22237-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2044931 INFO (coreLoadExecutor-22237-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2044931 INFO (coreLoadExecutor-22237-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2044932 INFO (coreLoadExecutor-22237-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=56.26953125, floorSegmentMB=1.49609375, forceMergeDeletesPctAllowed=15.355064232255964, segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4392499891505728, deletesPctAllowed=38.20519038713086 [junit4] 2> 2044933 INFO (coreLoadExecutor-22237-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 2044994 INFO (coreLoadExecutor-22237-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 2045068 INFO (coreLoadExecutor-22237-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 2045069 INFO (coreLoadExecutor-22237-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1711587916487065600 [junit4] 2> 2045070 INFO (searcherExecutor-22239-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 2045071 INFO (searcherExecutor-22239-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 2045071 INFO (searcherExecutor-22239-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 2045071 INFO (searcherExecutor-22239-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 2045071 INFO (searcherExecutor-22239-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 2045071 INFO (searcherExecutor-22239-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 2045071 INFO (searcherExecutor-22239-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 2045071 INFO (searcherExecutor-22239-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 2045071 INFO (searcherExecutor-22239-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 2045073 INFO (searcherExecutor-22239-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms [junit4] 2> 2045284 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] 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/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/shard0/cores/collection1 [junit4] 2> 2045285 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 2045285 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 2045285 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.Server jetty-9.4.41.v20210516; built: 2021-05-16T23:56:28.993Z; git: 98607f93c7833e7dc59489b13f3cb0a114fb9f4c; jvm 1.8.0_291-b10 [junit4] 2> 2045286 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2045286 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2045286 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 2045286 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5c9d8dbe{/_/l,null,AVAILABLE} [junit4] 2> 2045286 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2906aa77{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:40599} [junit4] 2> 2045286 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.Server Started @2045348ms [junit4] 2> 2045286 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/_/l, hostPort=40599, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/shard0/cores} [junit4] 2> 2045287 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2045287 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 2045287 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.10.0 [junit4] 2> 2045287 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 2045287 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr [junit4] 2> 2045287 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-09-22T07:57:16.443Z [junit4] 2> 2045287 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] 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/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/shard0/solr.xml [junit4] 2> 2045289 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 2045289 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 2045292 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6a185bfb, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 2046212 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 2046213 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@418a7f33[provider=null,keyStore=null,trustStore=null] [junit4] 2> 2046213 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@418a7f33[provider=null,keyStore=null,trustStore=null] [junit4] 2> 2046216 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6086d2d8[provider=null,keyStore=null,trustStore=null] [junit4] 2> 2046216 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6086d2d8[provider=null,keyStore=null,trustStore=null] [junit4] 2> 2046217 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] 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> 2046230 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4 [junit4] 2> 2046231 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 2046250 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a185bfb [junit4] 2> 2046262 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a185bfb [junit4] 2> 2046262 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a185bfb [junit4] 2> 2046264 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] 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/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/shard0/cores [junit4] 2> 2046264 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 2046271 INFO (coreLoadExecutor-22256-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> 2046288 INFO (coreLoadExecutor-22256-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.0 [junit4] 2> 2046288 WARN (coreLoadExecutor-22256-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 2046289 WARN (coreLoadExecutor-22256-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> 2046290 INFO (coreLoadExecutor-22256-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 2046291 INFO (coreLoadExecutor-22256-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 2046291 INFO (coreLoadExecutor-22256-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> 2046291 INFO (coreLoadExecutor-22256-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@6a185bfb [junit4] 2> 2046291 INFO (coreLoadExecutor-22256-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/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/shard0/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/shard0/cores/collection1/data/] [junit4] 2> 2046292 INFO (coreLoadExecutor-22256-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=45, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 2046358 INFO (coreLoadExecutor-22256-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2046358 INFO (coreLoadExecutor-22256-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2046359 INFO (coreLoadExecutor-22256-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2046359 INFO (coreLoadExecutor-22256-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2046360 INFO (coreLoadExecutor-22256-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=56.26953125, floorSegmentMB=1.49609375, forceMergeDeletesPctAllowed=15.355064232255964, segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4392499891505728, deletesPctAllowed=38.20519038713086 [junit4] 2> 2046361 INFO (coreLoadExecutor-22256-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 2046386 INFO (coreLoadExecutor-22256-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 2046446 INFO (coreLoadExecutor-22256-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 2046446 INFO (coreLoadExecutor-22256-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1711587917930954752 [junit4] 2> 2046447 INFO (searcherExecutor-22258-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 2046447 INFO (searcherExecutor-22258-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 2046447 INFO (searcherExecutor-22258-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 2046447 INFO (searcherExecutor-22258-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 2046447 INFO (searcherExecutor-22258-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 2046448 INFO (searcherExecutor-22258-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 2046448 INFO (searcherExecutor-22258-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 2046448 INFO (searcherExecutor-22258-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 2046448 INFO (searcherExecutor-22258-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 2046450 INFO (searcherExecutor-22258-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms [junit4] 2> 2046580 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] 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/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/shard1/cores/collection1 [junit4] 2> 2046581 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 2046581 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 2046581 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.Server jetty-9.4.41.v20210516; built: 2021-05-16T23:56:28.993Z; git: 98607f93c7833e7dc59489b13f3cb0a114fb9f4c; jvm 1.8.0_291-b10 [junit4] 2> 2046581 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2046581 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2046581 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 2046583 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2a1d303f{/_/l,null,AVAILABLE} [junit4] 2> 2046583 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@25cd3a47{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:38073} [junit4] 2> 2046583 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.Server Started @2046644ms [junit4] 2> 2046583 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/_/l, hostPort=38073, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/shard1/cores} [junit4] 2> 2046583 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2046583 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 2046583 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.10.0 [junit4] 2> 2046583 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 2046583 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr [junit4] 2> 2046583 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-09-22T07:57:17.739Z [junit4] 2> 2046583 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] 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/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/shard1/solr.xml [junit4] 2> 2046585 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 2046585 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 2046587 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6a185bfb, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 2047323 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 2047335 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@674daf26[provider=null,keyStore=null,trustStore=null] [junit4] 2> 2047335 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@674daf26[provider=null,keyStore=null,trustStore=null] [junit4] 2> 2047386 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7288a3f[provider=null,keyStore=null,trustStore=null] [junit4] 2> 2047386 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7288a3f[provider=null,keyStore=null,trustStore=null] [junit4] 2> 2047387 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] 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> 2047414 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4 [junit4] 2> 2047415 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 2047447 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a185bfb [junit4] 2> 2047468 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a185bfb [junit4] 2> 2047468 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a185bfb [junit4] 2> 2047469 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] 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/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/shard1/cores [junit4] 2> 2047469 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 2047497 INFO (coreLoadExecutor-22275-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> 2047525 INFO (coreLoadExecutor-22275-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.0 [junit4] 2> 2047525 WARN (coreLoadExecutor-22275-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 2047535 WARN (coreLoadExecutor-22275-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> 2047536 INFO (coreLoadExecutor-22275-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 2047537 INFO (coreLoadExecutor-22275-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 2047537 INFO (coreLoadExecutor-22275-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> 2047537 INFO (coreLoadExecutor-22275-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@6a185bfb [junit4] 2> 2047537 INFO (coreLoadExecutor-22275-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/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/shard1/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/shard1/cores/collection1/data/] [junit4] 2> 2047538 INFO (coreLoadExecutor-22275-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=45, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 2047973 INFO (coreLoadExecutor-22275-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2047973 INFO (coreLoadExecutor-22275-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2047974 INFO (coreLoadExecutor-22275-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2047974 INFO (coreLoadExecutor-22275-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2047975 INFO (coreLoadExecutor-22275-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=56.26953125, floorSegmentMB=1.49609375, forceMergeDeletesPctAllowed=15.355064232255964, segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4392499891505728, deletesPctAllowed=38.20519038713086 [junit4] 2> 2047976 INFO (coreLoadExecutor-22275-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 2048004 INFO (coreLoadExecutor-22275-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 2048265 INFO (coreLoadExecutor-22275-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 2048265 INFO (coreLoadExecutor-22275-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1711587919838314496 [junit4] 2> 2048267 INFO (searcherExecutor-22277-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 2048269 INFO (searcherExecutor-22277-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 2048269 INFO (searcherExecutor-22277-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 2048269 INFO (searcherExecutor-22277-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 2048269 INFO (searcherExecutor-22277-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 2048269 INFO (searcherExecutor-22277-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 2048269 INFO (searcherExecutor-22277-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 2048269 INFO (searcherExecutor-22277-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 2048269 INFO (searcherExecutor-22277-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 2048271 INFO (searcherExecutor-22277-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms [junit4] 2> 2048369 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] 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/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/shard2/cores/collection1 [junit4] 2> 2048370 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 2048370 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 2048370 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.Server jetty-9.4.41.v20210516; built: 2021-05-16T23:56:28.993Z; git: 98607f93c7833e7dc59489b13f3cb0a114fb9f4c; jvm 1.8.0_291-b10 [junit4] 2> 2048371 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2048371 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2048371 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 2048375 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@9e741ba{/_/l,null,AVAILABLE} [junit4] 2> 2048375 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@245aa42{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:34437} [junit4] 2> 2048375 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.s.Server Started @2048436ms [junit4] 2> 2048375 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/_/l, hostPort=34437, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/shard2/cores} [junit4] 2> 2048375 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2048375 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 2048375 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.10.0 [junit4] 2> 2048375 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 2048375 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr [junit4] 2> 2048375 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-09-22T07:57:19.531Z [junit4] 2> 2048375 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] 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/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/shard2/solr.xml [junit4] 2> 2048378 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 2048378 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 2048379 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6a185bfb, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 2049091 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 2049092 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5842c4d2[provider=null,keyStore=null,trustStore=null] [junit4] 2> 2049092 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5842c4d2[provider=null,keyStore=null,trustStore=null] [junit4] 2> 2049094 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2d8f53d2[provider=null,keyStore=null,trustStore=null] [junit4] 2> 2049094 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2d8f53d2[provider=null,keyStore=null,trustStore=null] [junit4] 2> 2049096 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] 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> 2049106 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4 [junit4] 2> 2049106 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 2049127 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a185bfb [junit4] 2> 2049137 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a185bfb [junit4] 2> 2049138 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a185bfb [junit4] 2> 2049139 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] 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/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/shard2/cores [junit4] 2> 2049139 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[4936F517EA232D4B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 2049146 INFO (coreLoadExecutor-22294-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> 2049177 INFO (coreLoadExecutor-22294-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.0 [junit4] 2> 2049177 WARN (coreLoadExecutor-22294-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 2049178 WARN (coreLoadExecutor-22294-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> 2049179 INFO (coreLoadExecutor-22294-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 2049180 INFO (coreLoadExecutor-22294-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 2049180 INFO (coreLoadExecutor-22294-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> 2049180 INFO (coreLoadExecutor-22294-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@6a185bfb [junit4] 2> 2049180 INFO (coreLoadExecutor-22294-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/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/shard2/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.10/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001/tempDir-001/shard2/cores/collection1/data/] [junit4] 2> 2049181 INFO (coreLoadExecutor-22294-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=45, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 2049448 INFO (coreLoadExecutor-22294-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2049448 INFO (coreLoadExecutor-22294-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2049449 INFO (coreLoadExecutor-22294-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2049449 INFO (coreLoadExecutor-22294-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2049450 INFO (coreLoadExecutor-22294-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=56.26953125, floorSegmentMB=1.49609375, forceMergeDeletesPctAllowed=15.355064232255964, segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4392499891505728, deletesPctAllowed=38.20519038713086 [junit4] 2> 2049451 INFO (coreLoadExecutor-22294-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 2049465 INFO (coreLoadExecutor-22294-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 2049524 INFO (coreLoadExecutor-22294-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 2049524 INFO (coreLoadExecutor-22294-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1711587921158471680 [junit4] 2> 2049525 INFO (searcherExecutor-22296-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 2049527 INFO (searcherExecutor-22296-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 2049527 INFO (searcherExecutor-22296-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 2049527 INFO [...truncated too long message...] mezone=Cuba -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 7.44s J2 | DistributedQueryComponentCustomSortTest.test <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:34437/_/l/collection1: org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded:[http://[::1]:8/_/l, http://[::1]:4/_/l, http://[::1]:6/_/l] [junit4] > at __randomizedtesting.SeedInfo.seed([4936F517EA232D4B:C162CACD44DF40B3]: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> 2050325 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 2050325 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1453998893 [junit4] 2> 2050325 INFO (coreCloseExecutor-22322-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@58105eed [junit4] 2> 2050325 INFO (coreCloseExecutor-22322-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@58105eed [junit4] 2> 2050325 INFO (coreCloseExecutor-22322-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2c506186: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@fe6ca04 [junit4] 2> 2050328 INFO (coreCloseExecutor-22322-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary). [junit4] 2> 2050330 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null [junit4] 2> 2050330 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7910b2c9: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@4e44ce52 [junit4] 2> 2050331 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null [junit4] 2> 2050331 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@48da876: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@1508c0d3 [junit4] 2> 2050331 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null [junit4] 2> 2050331 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[4936F517EA232D4B]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@f80162d: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@571601e0 [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/J2/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_4936F517EA232D4B-001 [junit4] 2> NOTE: test params are: codec=FastDecompressionCompressingStoredFieldsData(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=26803, maxDocsPerChunk=512, blockShift=9), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=26803, maxDocsPerChunk=512, blockSize=9)), sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=en-IE, timezone=Cuba [junit4] 2> NOTE: Linux 4.15.0-147-generic amd64/Oracle Corporation 1.8.0_291 (64-bit)/cpus=4,threads=2,free=123605336,total=527433728 [junit4] 2> NOTE: All tests run in this JVM: [TestShortCircuitedRequests, TestNumericTokenStream, DateMathParserTest, TestFieldCacheSortRandom, TestCloudJSONFacetSKG, TestStandardQParsers, TestRawResponseWriter, TestHdfsUpdateLog, OverseerStatusTest, TestReplicaProperties, TestSolrCloudWithKerberosAlt, TestDynamicFieldResource, NodeMutatorTest, CoreAdminRequestStatusTest, ShardTermsTest, TestDownShardTolerantSearch, ForceLeaderTest, ZkStateWriterTest, HttpPartitionTest, MigrateRouteKeyTest, TestTolerantUpdateProcessorRandomCloud, TestOrdValues, TestCollapseQParserPlugin, TestConfig, ByteBuffersDirectoryFactoryTest, TestFoldingMultitermQuery, PluginInfoTest, TestLeaderElectionWithEmptyReplica, BackupIdTest, TestNamedUpdateProcessors, TestSchemaVersionResource, TestDocumentBuilder, TestSimComputePlanAction, SSLMigrationTest, HdfsRecoverLeaseTest, TestTestInjection, TestRecovery, ConvertedLegacyTest, SolrSlf4jReporterTest, ZkNodePropsTest, TestSimNodeLostTrigger, SuggestComponentContextFilterQueryTest, ResourceLoaderTest, TestSolr4Spatial, TestSolrCoreSnapshots, SubstringBytesRefFilterTest, SplitHandlerTest, MoveReplicaTest, NodeLostTriggerIntegrationTest, TestTextField, EchoParamsTest, TriggerEventQueueTest, TaggerTest, TestCharFilters, DocumentAnalysisRequestHandlerTest, TestDistribIDF, HdfsBasicDistributedZkTest, TestCloudInspectUtil, TestPerFieldSimilarityWithDefaultOverride, AuditLoggerIntegrationTest, PeerSyncReplicationTest, OpenExchangeRatesOrgProviderTest, TestStressUserVersions, AnalyticsMergeStrategyTest, TestLegacyTerms, BlockJoinFacetDistribTest, BadComponentTest, TestHighFrequencyDictionaryFactory, TestCollectionAPI, TestRandomDVFaceting, TestJsonFacets, LeaderVoteWaitTimeoutTest, DefaultValueUpdateProcessorTest, TestHttpServletCarrier, PeerSyncWithBufferUpdatesTest, TestFastOutputStream, TestConfigSetsAPI, TestFieldTypeResource, TestPseudoReturnFields, IndexSizeEstimatorTest, ClusterStateUpdateTest, TestSolrIndexConfig, TestCustomSort, XMLAtomicUpdateMultivalueTest, NoCacheHeaderTest, CollectionsAPIAsyncDistributedZkTest, FacetPivotSmallTest, TestSimExtremeIndexing, TestSolrFieldCacheBean, ClassificationUpdateProcessorFactoryTest, TestSafeXMLParsing, PrimitiveFieldTypeTest, TestSchemaField, ConcurrentCreateRoutedAliasTest, SolrIndexMetricsTest, TestDocTermOrdsUninvertLimit, TestRestManager, LeaderElectionTest, PingRequestHandlerTest, PurgeGraphTest, MBeansHandlerTest, ScheduledMaintenanceTriggerTest, TestAuthorizationFramework, TestImplicitCoreProperties, TestCollectionAPIs, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestRandomCollapseQParserPlugin, DistributedFacetPivotLargeTest, SpellCheckCollatorTest, TestEmbeddedSolrServerConstructors, V2StandaloneTest, BooleanFieldTest, TestJettySolrRunner, ActionThrottleTest, AliasIntegrationTest, ChaosMonkeySafeLeaderWithPullReplicasTest, CreateCollectionCleanupTest, DeleteInactiveReplicaTest, DistribCursorPagingTest, DistribJoinFromCollectionTest, LegacyCloudClusterPropTest, MultiSolrCloudTestCaseTest, OverseerCollectionConfigSetProcessorTest, OverseerModifyCollectionTest, ReindexCollectionTest, SplitShardTest, TestAuthenticationFramework, TestCloudPivotFacet, TestCloudRecovery2, TestCryptoKeys, TestLockTree, TestOnReconnectListenerSupport, TestPullReplicaErrorHandling, TestRandomFlRTGCloud, TestSolrCloudWithSecureImpersonation, TestStressLiveNodes, AutoScalingHandlerTest, ExecutePlanActionTest, MetricTriggerIntegrationTest, ScheduledTriggerTest, SearchRateTriggerIntegrationTest, SearchRateTriggerTest, CdcrWithNodesRestartsTest, HdfsChaosMonkeyNothingIsSafeTest, HdfsRecoveryZkTest, HdfsRestartWhileUpdatingTest, HdfsSyncSliceTest, HdfsThreadLeakTest, HdfsTlogReplayBufferedWhileIndexingTest, HdfsUnloadDistributedZkTest, HdfsWriteToMultipleCollectionsTest, StressHdfsTest, TestClusterStateMutator, ImplicitSnitchTest, RuleEngineTest, RulesTest, SolrCoreTest, TestCoreDiscovery, TestJmxIntegration, TestMinimalConfig, TestQuerySenderListener, TestReloadAndDeleteDocs, TestSolrXml, FieldAnalysisRequestHandlerTest, SearchHandlerTest, TestBlobHandler, TestIncrementalCoreBackup, TestReplicationHandlerBackup, TestReqParamsAPI, TestSolrConfigHandlerCloud, DistributedQueryComponentCustomSortTest] [junit4] Completed [687/947 (1!)] on J2 in 9.48s, 1 test, 1 error <<< FAILURES! [...truncated 55114 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
