Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-NightlyTests-8.x/410/
1 tests failed. FAILED: org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.test Error Message: Error from server at https://127.0.0.1:43199/collection1: org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded:[https://[::1]:4, https://[::1]:8, https://[::1]:6] Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:43199/collection1: org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded:[https://[::1]:4, https://[::1]:8, https://[::1]:6] at __randomizedtesting.SeedInfo.seed([1E1168DB9289F785:964557013C759A7D]: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 15528 lines...] [junit4] Suite: org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest [junit4] 2> 4065498 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-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-NightlyTests-8.x/checkout/solr/server/solr/configsets/_default/conf' [junit4] 2> 4065499 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/data-dir-153-001 [junit4] 2> 4065499 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=16 numCloses=16 [junit4] 2> 4065499 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 4065504 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 4065505 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 4065505 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 4065505 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 4065512 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 4065531 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0 [junit4] 2> 4065531 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 4065531 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead [junit4] 2> 4065533 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 4065533 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 4065780 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.c.CoreContainer Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/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-NightlyTests-8.x/checkout/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-NightlyTests-8.x/checkout/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> 4065782 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.f.DistribPackageStore Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/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-NightlyTests-8.x/checkout/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-NightlyTests-8.x/checkout/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> 4065783 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 4065783 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 4065786 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6b390031[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4065786 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6b390031[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4065789 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 4065790 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6225f25c[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4065790 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6225f25c[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4065791 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-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> 4065822 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46f6770b [junit4] 2> 4065829 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46f6770b [junit4] 2> 4065829 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46f6770b [junit4] 2> 4065830 INFO (coreLoadExecutor-27194-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024 [junit4] 2> 4065841 INFO (coreLoadExecutor-27194-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 4065862 INFO (coreLoadExecutor-27194-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0 [junit4] 2> 4065862 WARN (coreLoadExecutor-27194-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 4065863 WARN (coreLoadExecutor-27194-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> 4065864 INFO (coreLoadExecutor-27194-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 4065865 INFO (coreLoadExecutor-27194-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 4065865 INFO (coreLoadExecutor-27194-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 4065865 INFO (coreLoadExecutor-27194-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@46f6770b [junit4] 2> 4065865 INFO (coreLoadExecutor-27194-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/data-dir-153-001/] [junit4] 2> 4065867 INFO (coreLoadExecutor-27194-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=8, maxMergeAtOnceExplicit=9, maxMergedSegmentMB=76.9423828125, floorSegmentMB=1.560546875, forceMergeDeletesPctAllowed=27.911133141143658, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.21392685107809034, deletesPctAllowed=46.83231361376281 [junit4] 2> 4065955 INFO (coreLoadExecutor-27194-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 4065955 INFO (coreLoadExecutor-27194-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 4065956 INFO (coreLoadExecutor-27194-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 4065956 INFO (coreLoadExecutor-27194-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 4065957 INFO (coreLoadExecutor-27194-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.22265625, noCFSRatio=0.0] [junit4] 2> 4065960 INFO (coreLoadExecutor-27194-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 4065969 INFO (coreLoadExecutor-27194-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 4066003 INFO (coreLoadExecutor-27194-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 4066003 INFO (coreLoadExecutor-27194-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1712606037511503872 [junit4] 2> 4066005 INFO (searcherExecutor-27196-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 4066007 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 4066008 INFO (searcherExecutor-27196-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 4066008 INFO (searcherExecutor-27196-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 4066008 INFO (searcherExecutor-27196-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 4066008 INFO (searcherExecutor-27196-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 4066008 INFO (searcherExecutor-27196-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 4066009 INFO (searcherExecutor-27196-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 4066009 INFO (searcherExecutor-27196-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 4066009 INFO (searcherExecutor-27196-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 4066010 INFO (searcherExecutor-27196-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms [junit4] 2> 4066135 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/control/cores/collection1 [junit4] 2> 4066136 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 4066136 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 4066136 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] 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> 4066140 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 4066140 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 4066140 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 4066141 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@25d49080{/,null,AVAILABLE} [junit4] 2> 4066141 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1e814acc{SSL, (ssl, http/1.1)}{127.0.0.1:35525} [junit4] 2> 4066141 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.Server Started @4066177ms [junit4] 2> 4066142 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/, hostPort=35525, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/control/cores} [junit4] 2> 4066142 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 4066142 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 4066142 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.11.0 [junit4] 2> 4066142 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 4066142 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr [junit4] 2> 4066142 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-10-03T13:39:52.236Z [junit4] 2> 4066142 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/control/solr.xml [junit4] 2> 4066145 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 4066145 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 4066146 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@46f6770b, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 4066706 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 4066707 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 4066707 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@42bb1b24[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4066707 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@42bb1b24[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4066713 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 4066714 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@23bda886[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4066714 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@23bda886[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4066716 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] 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> 4066732 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4 [junit4] 2> 4066732 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 4066756 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46f6770b [junit4] 2> 4066765 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46f6770b [junit4] 2> 4066766 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46f6770b [junit4] 2> 4066767 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/control/cores [junit4] 2> 4066767 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 4066777 INFO (coreLoadExecutor-27214-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 4066796 INFO (coreLoadExecutor-27214-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0 [junit4] 2> 4066796 WARN (coreLoadExecutor-27214-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 4066797 WARN (coreLoadExecutor-27214-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> 4066798 INFO (coreLoadExecutor-27214-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 4066798 INFO (coreLoadExecutor-27214-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 4066798 INFO (coreLoadExecutor-27214-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 4066799 INFO (coreLoadExecutor-27214-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@46f6770b [junit4] 2> 4066799 INFO (coreLoadExecutor-27214-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/control/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/control/cores/collection1/data/] [junit4] 2> 4066800 INFO (coreLoadExecutor-27214-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=8, maxMergeAtOnceExplicit=9, maxMergedSegmentMB=76.9423828125, floorSegmentMB=1.560546875, forceMergeDeletesPctAllowed=27.911133141143658, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.21392685107809034, deletesPctAllowed=46.83231361376281 [junit4] 2> 4066900 INFO (coreLoadExecutor-27214-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 4066900 INFO (coreLoadExecutor-27214-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 4066901 INFO (coreLoadExecutor-27214-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 4066901 INFO (coreLoadExecutor-27214-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 4066902 INFO (coreLoadExecutor-27214-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.22265625, noCFSRatio=0.0] [junit4] 2> 4066905 INFO (coreLoadExecutor-27214-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 4066926 INFO (coreLoadExecutor-27214-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 4066959 INFO (coreLoadExecutor-27214-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 4066960 INFO (coreLoadExecutor-27214-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1712606038514991104 [junit4] 2> 4066961 INFO (searcherExecutor-27216-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 4066962 INFO (searcherExecutor-27216-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 4066962 INFO (searcherExecutor-27216-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 4066962 INFO (searcherExecutor-27216-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 4066962 INFO (searcherExecutor-27216-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 4066962 INFO (searcherExecutor-27216-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 4066963 INFO (searcherExecutor-27216-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 4066964 INFO (searcherExecutor-27216-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 4066964 INFO (searcherExecutor-27216-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 4066966 INFO (searcherExecutor-27216-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms [junit4] 2> 4067107 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/shard0/cores/collection1 [junit4] 2> 4067109 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 4067109 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 4067109 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] 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> 4067114 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 4067114 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 4067114 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 4067114 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7d5c7204{/,null,AVAILABLE} [junit4] 2> 4067115 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3b804d07{SSL, (ssl, http/1.1)}{127.0.0.1:46753} [junit4] 2> 4067115 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.Server Started @4067151ms [junit4] 2> 4067115 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/, hostPort=46753, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/shard0/cores} [junit4] 2> 4067115 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 4067115 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 4067115 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.11.0 [junit4] 2> 4067115 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 4067115 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr [junit4] 2> 4067115 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-10-03T13:39:53.209Z [junit4] 2> 4067115 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/shard0/solr.xml [junit4] 2> 4067118 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 4067118 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 4067120 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@46f6770b, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 4068824 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 4068825 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 4068826 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5136fc4f[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4068826 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5136fc4f[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4068829 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 4068830 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4272c921[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4068830 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4272c921[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4068831 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] 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> 4068844 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4 [junit4] 2> 4068844 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 4068872 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46f6770b [junit4] 2> 4068887 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46f6770b [junit4] 2> 4068887 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46f6770b [junit4] 2> 4068889 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/shard0/cores [junit4] 2> 4068889 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 4068910 INFO (coreLoadExecutor-27233-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 4068931 INFO (coreLoadExecutor-27233-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0 [junit4] 2> 4068931 WARN (coreLoadExecutor-27233-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 4068932 WARN (coreLoadExecutor-27233-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> 4068934 INFO (coreLoadExecutor-27233-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 4068934 INFO (coreLoadExecutor-27233-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 4068934 INFO (coreLoadExecutor-27233-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 4068934 INFO (coreLoadExecutor-27233-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@46f6770b [junit4] 2> 4068935 INFO (coreLoadExecutor-27233-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/shard0/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/shard0/cores/collection1/data/] [junit4] 2> 4068936 INFO (coreLoadExecutor-27233-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=8, maxMergeAtOnceExplicit=9, maxMergedSegmentMB=76.9423828125, floorSegmentMB=1.560546875, forceMergeDeletesPctAllowed=27.911133141143658, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.21392685107809034, deletesPctAllowed=46.83231361376281 [junit4] 2> 4069022 INFO (coreLoadExecutor-27233-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 4069022 INFO (coreLoadExecutor-27233-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 4069023 INFO (coreLoadExecutor-27233-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 4069023 INFO (coreLoadExecutor-27233-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 4069024 INFO (coreLoadExecutor-27233-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.22265625, noCFSRatio=0.0] [junit4] 2> 4069027 INFO (coreLoadExecutor-27233-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 4069040 INFO (coreLoadExecutor-27233-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 4069070 INFO (coreLoadExecutor-27233-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 4069070 INFO (coreLoadExecutor-27233-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1712606040727486464 [junit4] 2> 4069071 INFO (searcherExecutor-27235-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 4069072 INFO (searcherExecutor-27235-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 4069072 INFO (searcherExecutor-27235-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 4069072 INFO (searcherExecutor-27235-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 4069072 INFO (searcherExecutor-27235-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 4069073 INFO (searcherExecutor-27235-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 4069073 INFO (searcherExecutor-27235-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 4069073 INFO (searcherExecutor-27235-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 4069073 INFO (searcherExecutor-27235-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 4069075 INFO (searcherExecutor-27235-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms [junit4] 2> 4069234 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/shard1/cores/collection1 [junit4] 2> 4069235 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 4069235 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 4069235 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] 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> 4069244 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 4069244 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 4069244 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 4069244 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@a24981{/,null,AVAILABLE} [junit4] 2> 4069245 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@69c7f77{SSL, (ssl, http/1.1)}{127.0.0.1:39663} [junit4] 2> 4069245 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.Server Started @4069281ms [junit4] 2> 4069245 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/, hostPort=39663, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/shard1/cores} [junit4] 2> 4069245 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 4069245 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 4069245 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.11.0 [junit4] 2> 4069245 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 4069245 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr [junit4] 2> 4069245 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-10-03T13:39:55.339Z [junit4] 2> 4069245 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/shard1/solr.xml [junit4] 2> 4069248 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 4069248 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 4069250 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@46f6770b, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 4070510 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 4070511 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 4070512 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@54a43c10[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4070512 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@54a43c10[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4070516 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 4070518 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@10c8f3a5[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4070518 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@10c8f3a5[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4070519 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] 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> 4070541 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4 [junit4] 2> 4070541 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 4070770 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46f6770b [junit4] 2> 4070785 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46f6770b [junit4] 2> 4070785 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46f6770b [junit4] 2> 4070787 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/shard1/cores [junit4] 2> 4070787 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 4070831 INFO (coreLoadExecutor-27252-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 4070858 INFO (coreLoadExecutor-27252-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0 [junit4] 2> 4070858 WARN (coreLoadExecutor-27252-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 4070859 WARN (coreLoadExecutor-27252-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> 4070861 INFO (coreLoadExecutor-27252-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 4070880 INFO (coreLoadExecutor-27252-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 4070880 INFO (coreLoadExecutor-27252-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 4070880 INFO (coreLoadExecutor-27252-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@46f6770b [junit4] 2> 4070880 INFO (coreLoadExecutor-27252-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/shard1/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/shard1/cores/collection1/data/] [junit4] 2> 4070882 INFO (coreLoadExecutor-27252-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=8, maxMergeAtOnceExplicit=9, maxMergedSegmentMB=76.9423828125, floorSegmentMB=1.560546875, forceMergeDeletesPctAllowed=27.911133141143658, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.21392685107809034, deletesPctAllowed=46.83231361376281 [junit4] 2> 4070972 INFO (coreLoadExecutor-27252-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 4070972 INFO (coreLoadExecutor-27252-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 4070973 INFO (coreLoadExecutor-27252-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 4070973 INFO (coreLoadExecutor-27252-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 4070975 INFO (coreLoadExecutor-27252-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.22265625, noCFSRatio=0.0] [junit4] 2> 4070978 INFO (coreLoadExecutor-27252-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 4070985 INFO (coreLoadExecutor-27252-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 4071007 INFO (coreLoadExecutor-27252-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 4071007 INFO (coreLoadExecutor-27252-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1712606042758578176 [junit4] 2> 4071009 INFO (searcherExecutor-27254-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 4071009 INFO (searcherExecutor-27254-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 4071009 INFO (searcherExecutor-27254-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 4071009 INFO (searcherExecutor-27254-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 4071009 INFO (searcherExecutor-27254-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 4071009 INFO (searcherExecutor-27254-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 4071009 INFO (searcherExecutor-27254-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 4071010 INFO (searcherExecutor-27254-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 4071010 INFO (searcherExecutor-27254-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 4071012 INFO (searcherExecutor-27254-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms [junit4] 2> 4071167 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/shard2/cores/collection1 [junit4] 2> 4071169 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 4071169 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 4071169 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] 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> 4071387 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 4071387 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 4071387 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 4071388 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6d6e7108{/,null,AVAILABLE} [junit4] 2> 4071389 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6c59d5ce{SSL, (ssl, http/1.1)}{127.0.0.1:43199} [junit4] 2> 4071389 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.s.Server Started @4071425ms [junit4] 2> 4071389 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/, hostPort=43199, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/shard2/cores} [junit4] 2> 4071389 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 4071389 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 4071389 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.11.0 [junit4] 2> 4071390 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 4071390 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr [junit4] 2> 4071390 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-10-03T13:39:57.484Z [junit4] 2> 4071390 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/shard2/solr.xml [junit4] 2> 4071392 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 4071392 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 4071394 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@46f6770b, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 4072357 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 4072358 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 4072359 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3767562f[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4072359 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3767562f[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4072362 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 4072362 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@74aea8cf[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4072362 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@74aea8cf[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4072364 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] 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> 4072376 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4 [junit4] 2> 4072376 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 4072568 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46f6770b [junit4] 2> 4072581 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46f6770b [junit4] 2> 4072581 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46f6770b [junit4] 2> 4072583 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001/tempDir-001/shard2/cores [junit4] 2> 4072583 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[1E1168DB9289F785]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 4072603 INFO (coreLoadExecutor-27271-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 4072643 INFO (coreLoadExecutor-27271-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0 [junit4] 2> 4072643 WARN (coreLoadExecutor-27271-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 4072644 WARN (coreLoadExecutor-27271-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> 4072646 INFO (coreLoadExecutor-27271-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 4072646 INFO (coreLoadExecutor-27271-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 4072646 INFO (coreLoadExecutor-27271-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 4072646 INFO (coreLoadExecutor-27271-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@46f6770b [junit4] 2> 4072647 INFO (coreLoadExecutor-27271-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.com [...truncated too long message...] t test -Dtestcase=DistributedQueryComponentCustomSortTest -Dtests.method=test -Dtests.seed=1E1168DB9289F785 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/test-data/enwiki.random.lines.txt -Dtests.locale=it-CH -Dtests.timezone=Asia/Jayapura -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 7.87s J3 | DistributedQueryComponentCustomSortTest.test <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:43199/collection1: org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded:[https://[::1]:4, https://[::1]:8, https://[::1]:6] [junit4] > at __randomizedtesting.SeedInfo.seed([1E1168DB9289F785:964557013C759A7D]: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> 4073876 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 4073876 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=950072150 [junit4] 2> 4073876 INFO (coreCloseExecutor-27299-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2da3edae [junit4] 2> 4073877 INFO (coreCloseExecutor-27299-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@2da3edae [junit4] 2> 4073877 INFO (coreCloseExecutor-27299-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5135b8c1: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@76f5f8c7 [junit4] 2> 4073881 INFO (coreCloseExecutor-27299-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary). [junit4] 2> 4073882 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null [junit4] 2> 4073882 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@421b196c: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@6ae00359 [junit4] 2> 4073883 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null [junit4] 2> 4073883 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@32f5db62: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@4169cd85 [junit4] 2> 4073884 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null [junit4] 2> 4073884 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[1E1168DB9289F785]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@aa5421c: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@10071fbd [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_1E1168DB9289F785-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {_root_=PostingsFormat(name=LuceneVarGapDocFreqInterval), id=BlockTreeOrds(blocksize=128), text=FST50, asdfasdf_field_should_match_catchall_dynamic_field_adsfasdf=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{payload=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1849, maxMBSortInHeap=6.755258769685833, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=it-CH, timezone=Asia/Jayapura [junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_291 (64-bit)/cpus=4,threads=1,free=171149448,total=525860864 [junit4] 2> NOTE: All tests run in this JVM: [FieldAnalysisRequestHandlerTest, TestQuerySenderNoQuery, TaggingAttributeTest, AtomicUpdateProcessorFactoryTest, TestOverriddenPrefixQueryForCustomFieldType, RequestLoggingTest, OverseerTaskQueueTest, CloudReplicaSourceTest, TestFieldCacheVsDocValues, TestExactSharedStatsCache, MissingSegmentRecoveryTest, CdcrUpdateLogTest, TestMergePolicyConfig, TestUseDocValuesAsStored, ZkCLITest, HdfsRecoveryZkTest, TestUtils, InfixSuggestersTest, DirectSolrSpellCheckerTest, DistanceUnitsTest, TestMultiValuedNumericRangeQuery, TestRandomFaceting, TestSubQueryTransformerDistrib, TestRestoreCore, QueryParsingTest, MultiDestinationAuditLoggerTest, TestTlogReplayVsRecovery, ImplicitSnitchTest, AuditLoggerPluginTest, SparseHLLTest, CoreAdminCreateDiscoverTest, TestComplexPhraseLeadingWildcard, TestManagedSchemaThreadSafety, TestDistributedGrouping, UpdateLogTest, TestSchemaNameResource, TestIndexingPerformance, TokenizerChainTest, TestDFRSimilarityFactory, RollingRestartTest, TestDynamicFieldNamesIndexCorrectly, TestJavabinTupleStreamParser, WrapperMergePolicyFactoryTest, TestDistribPackageStore, TestGraphTermsQParserPlugin, TestRawTransformer, TestLogWatcher, TestValueSourceCache, TestSimDistribStateManager, DistributedDebugComponentTest, TestStressVersions, AlternateDirectoryTest, FullHLLTest, TestSearcherReuse, TestSolr4Spatial2, TestFieldCache, CloudExitableDirectoryReaderTest, PeerSyncWithIndexFingerprintCachingTest, ZookeeperReadAPITest, ClassificationUpdateProcessorIntegrationTest, DistributedMLTComponentTest, CoreMergeIndexesAdminHandlerTest, TlogReplayBufferedWhileIndexingTest, ParsingFieldUpdateProcessorsTest, SolrXmlInZkTest, TestEmbeddedSolrServerSchemaAPI, TestDistributedStatsComponentCardinality, ShardSplitTest, TestConfigReload, TestStressThreadBackup, TestCoreContainer, BigEndianAscendingWordDeserializerTest, ShardsWhitelistTest, ComputePlanActionTest, TestSolrConfigHandler, TestStressLucene, SolrIndexSplitterTest, TestBM25SimilarityFactory, AddReplicaTest, TestQueryingOnDownCollection, LegacyCloudClusterPropTest, TestBulkSchemaConcurrent, SolrCloudReportersTest, DirectoryFactoryTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, StatsReloadRaceTest, TestChildDocTransformerHierarchy, IndexSizeTriggerMixedBoundsTest, TestSnapshotCoreBackup, TestNumericTerms64, SortByFunctionTest, TestQueryTypes, TestSolrXml, ChaosMonkeySafeLeaderTest, SystemLogListenerTest, TestSizeLimitedDistributedMap, TestCloudNestedDocsSort, XmlUpdateRequestHandlerTest, TestSweetSpotSimilarityFactory, TestClusterStateMutator, HighlighterMaxOffsetTest, DistributedFacetPivotLongTailTest, CollectionPropsTest, TestGroupingSearch, DimensionalRoutedAliasUpdateProcessorTest, TestJsonFacetsStatsParsing, OrderedExecutorTest, TestAddFieldRealTimeGet, TestMacros, UUIDUpdateProcessorFallbackTest, SuggestComponentTest, LeaderFailoverAfterPartitionTest, AnalyticsQueryTest, CdcrOpsAndBoundariesTest, TestAuthenticationFramework, TestCloudRecovery2, TestReplicaProperties, AutoAddReplicasIntegrationTest, TriggerIntegrationTest, CdcrReplicationHandlerTest, HdfsTlogReplayBufferedWhileIndexingTest, QueryResultKeyTest, TestConfigOverlay, TestCoreDiscovery, TestDynamicLoading, TestSolrCoreSnapshots, JsonLoaderTest, MoreLikeThisHandlerTest, PingRequestHandlerTest, TestHdfsBackupRestoreCore, TestIncrementalCoreBackup, TestReplicationHandlerBackup, TestReplicationHandlerDiskOverFlow, TestReqParamsAPI, TestSQLHandler, TestSQLHandlerNonCloud, TestSystemCollAutoCreate, V2StandaloneTest, XsltUpdateRequestHandlerTest, AdminHandlersProxyTest, CoreAdminOperationTest, CoreAdminRequestStatusTest, DaemonStreamApiTest, IndexSizeEstimatorTest, MBeansHandlerTest, DebugComponentTest, DistributedFacetPivotSmallAdvancedTest, DistributedFacetPivotSmallTest, DistributedQueryComponentCustomSortTest] [junit4] Completed [701/947 (1!)] on J3 in 8.60s, 1 test, 1 error <<< FAILURES! [...truncated 55104 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
