Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.x/3251/
1 tests failed. FAILED: org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.test Error Message: Error from server at http://127.0.0.1:39455/collection1: org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded:[http://[::1]:4, http://[::1]:8, http://[::1]:6] Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:39455/collection1: org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded:[http://[::1]:4, http://[::1]:8, http://[::1]:6] at __randomizedtesting.SeedInfo.seed([450456CCC465615B:CD5069166A990CA3]: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 13662 lines...] [junit4] Suite: org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest [junit4] 2> 163632 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/server/solr/configsets/_default/conf' [junit4] 2> 163632 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/data-dir-38-001 [junit4] 2> 163632 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=10 numCloses=10 [junit4] 2> 163632 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 163634 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 163634 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 163635 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 163635 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 163659 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 163700 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0 [junit4] 2> 163700 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 163700 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead [junit4] 2> 163702 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 163703 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 164243 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.c.CoreContainer Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/userfiles]. Features requiring this directory may fail. [junit4] 2> => java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/userfiles" "write") [junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) [junit4] 2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/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> 164245 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.f.DistribPackageStore Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr]. Features requiring this directory may fail. [junit4] 2> => java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/filestore" "write") [junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) [junit4] 2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/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> 164246 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 164247 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5b1d7f21[provider=null,keyStore=null,trustStore=null] [junit4] 2> 164248 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5b1d7f21[provider=null,keyStore=null,trustStore=null] [junit4] 2> 164251 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@535e8a4e[provider=null,keyStore=null,trustStore=null] [junit4] 2> 164251 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@535e8a4e[provider=null,keyStore=null,trustStore=null] [junit4] 2> 164252 WARN (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-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> 164284 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50570b2 [junit4] 2> 164292 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50570b2 [junit4] 2> 164292 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50570b2 [junit4] 2> 164295 INFO (coreLoadExecutor-1987-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024 [junit4] 2> 164304 INFO (coreLoadExecutor-1987-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 164322 INFO (coreLoadExecutor-1987-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0 [junit4] 2> 164323 WARN (coreLoadExecutor-1987-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 164323 WARN (coreLoadExecutor-1987-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> 164324 INFO (coreLoadExecutor-1987-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 164325 INFO (coreLoadExecutor-1987-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 164325 INFO (coreLoadExecutor-1987-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 164325 INFO (coreLoadExecutor-1987-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@50570b2 [junit4] 2> 164326 INFO (coreLoadExecutor-1987-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/data-dir-38-001/] [junit4] 2> 164327 INFO (coreLoadExecutor-1987-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4752765011786412] [junit4] 2> 164391 INFO (coreLoadExecutor-1987-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 164391 INFO (coreLoadExecutor-1987-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 164405 INFO (coreLoadExecutor-1987-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 164405 INFO (coreLoadExecutor-1987-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 164406 INFO (coreLoadExecutor-1987-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=46, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.46942862441458155] [junit4] 2> 164408 INFO (coreLoadExecutor-1987-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 164423 INFO (coreLoadExecutor-1987-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 164457 INFO (coreLoadExecutor-1987-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 164458 INFO (coreLoadExecutor-1987-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1712060685225033728 [junit4] 2> 164459 INFO (searcherExecutor-1989-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 164459 INFO (searcherExecutor-1989-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 164459 INFO (searcherExecutor-1989-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 164459 INFO (searcherExecutor-1989-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 164460 INFO (searcherExecutor-1989-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 164460 INFO (searcherExecutor-1989-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 164460 INFO (searcherExecutor-1989-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 164460 INFO (searcherExecutor-1989-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 164460 INFO (searcherExecutor-1989-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 164461 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 164462 INFO (searcherExecutor-1989-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms [junit4] 2> 164585 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/control/cores/collection1 [junit4] 2> 164586 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 164586 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 164587 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] 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> 164587 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 164587 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 164587 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 164588 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2f0af1f2{/,null,AVAILABLE} [junit4] 2> 164588 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@167633cc{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:35291} [junit4] 2> 164588 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.Server Started @164625ms [junit4] 2> 164588 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/, hostPort=35291, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/control/cores} [junit4] 2> 164589 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 164589 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 164589 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.11.0 [junit4] 2> 164589 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 164589 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr [junit4] 2> 164589 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-09-27T13:11:43.759Z [junit4] 2> 164589 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/control/solr.xml [junit4] 2> 164592 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 164592 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 164593 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@50570b2, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 165544 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 165552 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@55e36fff[provider=null,keyStore=null,trustStore=null] [junit4] 2> 165553 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@55e36fff[provider=null,keyStore=null,trustStore=null] [junit4] 2> 165567 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@19a0b0f8[provider=null,keyStore=null,trustStore=null] [junit4] 2> 165567 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@19a0b0f8[provider=null,keyStore=null,trustStore=null] [junit4] 2> 165568 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] 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> 165580 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4 [junit4] 2> 165580 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 165601 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50570b2 [junit4] 2> 165610 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50570b2 [junit4] 2> 165610 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50570b2 [junit4] 2> 165612 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/control/cores [junit4] 2> 165612 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 165630 INFO (coreLoadExecutor-2007-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 165657 INFO (coreLoadExecutor-2007-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0 [junit4] 2> 165657 WARN (coreLoadExecutor-2007-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 165657 WARN (coreLoadExecutor-2007-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> 165659 INFO (coreLoadExecutor-2007-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 165659 INFO (coreLoadExecutor-2007-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 165659 INFO (coreLoadExecutor-2007-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 165659 INFO (coreLoadExecutor-2007-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@50570b2 [junit4] 2> 165660 INFO (coreLoadExecutor-2007-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/control/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/control/cores/collection1/data/] [junit4] 2> 165661 INFO (coreLoadExecutor-2007-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4752765011786412] [junit4] 2> 165728 INFO (coreLoadExecutor-2007-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 165729 INFO (coreLoadExecutor-2007-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 165730 INFO (coreLoadExecutor-2007-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 165730 INFO (coreLoadExecutor-2007-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 165731 INFO (coreLoadExecutor-2007-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=46, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.46942862441458155] [junit4] 2> 165732 INFO (coreLoadExecutor-2007-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 165742 INFO (coreLoadExecutor-2007-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 165777 INFO (coreLoadExecutor-2007-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 165777 INFO (coreLoadExecutor-2007-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1712060686608105472 [junit4] 2> 165779 INFO (searcherExecutor-2009-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 165779 INFO (searcherExecutor-2009-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 165779 INFO (searcherExecutor-2009-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 165779 INFO (searcherExecutor-2009-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 165779 INFO (searcherExecutor-2009-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 165780 INFO (searcherExecutor-2009-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 165780 INFO (searcherExecutor-2009-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 165780 INFO (searcherExecutor-2009-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 165780 INFO (searcherExecutor-2009-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 165782 INFO (searcherExecutor-2009-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms [junit4] 2> 165902 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/shard0/cores/collection1 [junit4] 2> 165903 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 165903 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 165903 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] 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> 165904 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 165904 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 165904 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 165906 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@74e37f34{/,null,AVAILABLE} [junit4] 2> 165907 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6aa248e7{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:39455} [junit4] 2> 165907 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.Server Started @165944ms [junit4] 2> 165907 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/, hostPort=39455, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/shard0/cores} [junit4] 2> 165907 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 165907 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 165907 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.11.0 [junit4] 2> 165907 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 165907 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr [junit4] 2> 165907 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-09-27T13:11:45.077Z [junit4] 2> 165907 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/shard0/solr.xml [junit4] 2> 165910 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 165910 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 165911 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@50570b2, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 166186 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 166187 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3dd6e182[provider=null,keyStore=null,trustStore=null] [junit4] 2> 166187 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3dd6e182[provider=null,keyStore=null,trustStore=null] [junit4] 2> 166190 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@654c13ac[provider=null,keyStore=null,trustStore=null] [junit4] 2> 166190 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@654c13ac[provider=null,keyStore=null,trustStore=null] [junit4] 2> 166191 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] 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> 166203 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4 [junit4] 2> 166203 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 166226 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50570b2 [junit4] 2> 166238 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50570b2 [junit4] 2> 166238 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50570b2 [junit4] 2> 166239 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/shard0/cores [junit4] 2> 166239 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 166249 INFO (coreLoadExecutor-2026-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 166268 INFO (coreLoadExecutor-2026-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0 [junit4] 2> 166268 WARN (coreLoadExecutor-2026-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 166269 WARN (coreLoadExecutor-2026-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> 166271 INFO (coreLoadExecutor-2026-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 166271 INFO (coreLoadExecutor-2026-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 166271 INFO (coreLoadExecutor-2026-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 166271 INFO (coreLoadExecutor-2026-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@50570b2 [junit4] 2> 166272 INFO (coreLoadExecutor-2026-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/shard0/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/shard0/cores/collection1/data/] [junit4] 2> 166273 INFO (coreLoadExecutor-2026-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4752765011786412] [junit4] 2> 166347 INFO (coreLoadExecutor-2026-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 166347 INFO (coreLoadExecutor-2026-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 166349 INFO (coreLoadExecutor-2026-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 166349 INFO (coreLoadExecutor-2026-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 166350 INFO (coreLoadExecutor-2026-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=46, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.46942862441458155] [junit4] 2> 166351 INFO (coreLoadExecutor-2026-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 166360 INFO (coreLoadExecutor-2026-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 166402 INFO (coreLoadExecutor-2026-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 166402 INFO (coreLoadExecutor-2026-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1712060687263465472 [junit4] 2> 166404 INFO (searcherExecutor-2028-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 166405 INFO (searcherExecutor-2028-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 166406 INFO (searcherExecutor-2028-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 166406 INFO (searcherExecutor-2028-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 166406 INFO (searcherExecutor-2028-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 166406 INFO (searcherExecutor-2028-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 166406 INFO (searcherExecutor-2028-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 166406 INFO (searcherExecutor-2028-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 166406 INFO (searcherExecutor-2028-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 166410 INFO (searcherExecutor-2028-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms [junit4] 2> 166548 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/shard1/cores/collection1 [junit4] 2> 166549 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 166549 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 166549 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] 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> 166551 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 166551 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 166551 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 166552 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@345997f3{/,null,AVAILABLE} [junit4] 2> 166552 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@173bcf0b{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:39915} [junit4] 2> 166552 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.Server Started @166589ms [junit4] 2> 166552 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/, hostPort=39915, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/shard1/cores} [junit4] 2> 166553 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 166553 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 166553 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.11.0 [junit4] 2> 166553 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 166553 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr [junit4] 2> 166553 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-09-27T13:11:45.723Z [junit4] 2> 166553 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/shard1/solr.xml [junit4] 2> 166555 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 166555 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 166557 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@50570b2, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 166992 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 166993 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@18aa4e95[provider=null,keyStore=null,trustStore=null] [junit4] 2> 166993 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@18aa4e95[provider=null,keyStore=null,trustStore=null] [junit4] 2> 166997 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@747a6b95[provider=null,keyStore=null,trustStore=null] [junit4] 2> 166997 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@747a6b95[provider=null,keyStore=null,trustStore=null] [junit4] 2> 166998 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] 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> 167011 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4 [junit4] 2> 167011 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 167035 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50570b2 [junit4] 2> 167048 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50570b2 [junit4] 2> 167048 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50570b2 [junit4] 2> 167049 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/shard1/cores [junit4] 2> 167049 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 167058 INFO (coreLoadExecutor-2045-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 167077 INFO (coreLoadExecutor-2045-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0 [junit4] 2> 167077 WARN (coreLoadExecutor-2045-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 167077 WARN (coreLoadExecutor-2045-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> 167088 INFO (coreLoadExecutor-2045-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 167089 INFO (coreLoadExecutor-2045-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 167089 INFO (coreLoadExecutor-2045-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 167089 INFO (coreLoadExecutor-2045-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@50570b2 [junit4] 2> 167089 INFO (coreLoadExecutor-2045-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/shard1/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/shard1/cores/collection1/data/] [junit4] 2> 167091 INFO (coreLoadExecutor-2045-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4752765011786412] [junit4] 2> 167148 INFO (coreLoadExecutor-2045-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 167148 INFO (coreLoadExecutor-2045-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 167149 INFO (coreLoadExecutor-2045-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 167149 INFO (coreLoadExecutor-2045-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 167150 INFO (coreLoadExecutor-2045-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=46, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.46942862441458155] [junit4] 2> 167151 INFO (coreLoadExecutor-2045-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 167158 INFO (coreLoadExecutor-2045-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 167182 INFO (coreLoadExecutor-2045-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 167182 INFO (coreLoadExecutor-2045-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1712060688081354752 [junit4] 2> 167184 INFO (searcherExecutor-2047-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 167184 INFO (searcherExecutor-2047-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 167184 INFO (searcherExecutor-2047-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 167184 INFO (searcherExecutor-2047-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 167184 INFO (searcherExecutor-2047-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 167184 INFO (searcherExecutor-2047-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 167185 INFO (searcherExecutor-2047-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 167185 INFO (searcherExecutor-2047-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 167185 INFO (searcherExecutor-2047-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 167187 INFO (searcherExecutor-2047-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms [junit4] 2> 167310 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/shard2/cores/collection1 [junit4] 2> 167311 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 167311 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 167311 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] 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> 167312 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 167312 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 167312 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 167316 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3a6e2c88{/,null,AVAILABLE} [junit4] 2> 167317 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@52091add{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:33315} [junit4] 2> 167317 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.s.Server Started @167354ms [junit4] 2> 167317 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/, hostPort=33315, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/shard2/cores} [junit4] 2> 167317 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 167317 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 167317 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.11.0 [junit4] 2> 167317 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 167317 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr [junit4] 2> 167317 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-09-27T13:11:46.487Z [junit4] 2> 167317 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/shard2/solr.xml [junit4] 2> 167320 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 167320 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 167322 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@50570b2, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 169324 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 169325 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4f105aec[provider=null,keyStore=null,trustStore=null] [junit4] 2> 169325 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4f105aec[provider=null,keyStore=null,trustStore=null] [junit4] 2> 169332 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1de7e757[provider=null,keyStore=null,trustStore=null] [junit4] 2> 169333 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1de7e757[provider=null,keyStore=null,trustStore=null] [junit4] 2> 169334 WARN (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] 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> 169347 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4 [junit4] 2> 169347 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 169369 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50570b2 [junit4] 2> 169382 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50570b2 [junit4] 2> 169382 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@50570b2 [junit4] 2> 169384 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/shard2/cores [junit4] 2> 169384 INFO (TEST-DistributedQueryComponentCustomSortTest.test-seed#[450456CCC465615B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 169417 INFO (coreLoadExecutor-2064-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 169441 INFO (coreLoadExecutor-2064-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0 [junit4] 2> 169441 WARN (coreLoadExecutor-2064-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect. [junit4] 2> 169441 WARN (coreLoadExecutor-2064-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> 169444 INFO (coreLoadExecutor-2064-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort [junit4] 2> 169445 INFO (coreLoadExecutor-2064-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id [junit4] 2> 169445 INFO (coreLoadExecutor-2064-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 169445 INFO (coreLoadExecutor-2064-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@50570b2 [junit4] 2> 169445 INFO (coreLoadExecutor-2064-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/shard2/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001/tempDir-001/shard2/cores/collection1/data/] [junit4] 2> 169447 INFO (coreLoadExecutor-2064-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4752765011786412] [junit4] 2> 169520 INFO (coreLoadExecutor-2064-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 169520 INFO (coreLoadExecutor-2064-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 169521 INFO (coreLoadExecutor-2064-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 169521 INFO (coreLoadExecutor-2064-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 169522 INFO (coreLoadExecutor-2064-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=46, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.46942862441458155] [junit4] 2> 169525 INFO (coreLoadExecutor-2064-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 169539 INFO (coreLoadExecutor-2064-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 169569 INFO (coreLoadExecutor-2064-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 169569 INFO (coreLoadExecutor-2064-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1712060690584305664 [junit4] 2> 169570 INFO (searcherExecutor-2066-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 169571 INFO (searcherExecutor-2066-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 169571 INFO (searcherExecutor-2066-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 169571 INFO (searcherExecutor-2066-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 169571 INFO (searcherExecutor-2066-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 169571 INFO (searcherExecutor-2066-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 169571 INFO (searcherExecutor-2066-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Lo [...truncated too long message...] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1edbfb11: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@1e9a23a3 [junit4] 2> 170029 INFO (closeThreadPool-2074-thread-6) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@74e37f34{/,null,STOPPED} [junit4] 2> 170029 INFO (closeThreadPool-2074-thread-6) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 170036 INFO (closeThreadPool-2074-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null [junit4] 2> 170036 INFO (closeThreadPool-2074-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5c4efa5b: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@4afeaf3b [junit4] 2> 170040 INFO (closeThreadPool-2074-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@167633cc{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0} [junit4] 2> 170044 INFO (closeThreadPool-2074-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2f0af1f2{/,null,STOPPED} [junit4] 2> 170044 INFO (closeThreadPool-2074-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 170047 INFO (closeThreadPool-2074-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null [junit4] 2> 170047 INFO (closeThreadPool-2074-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3f2aa3d5: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@1ca72d7f [junit4] 2> 170050 INFO (closeThreadPool-2074-thread-7) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@173bcf0b{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0} [junit4] 2> 170050 INFO (closeThreadPool-2074-thread-7) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@345997f3{/,null,STOPPED} [junit4] 2> 170050 INFO (closeThreadPool-2074-thread-7) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 170053 INFO (closeThreadPool-2074-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null [junit4] 2> 170053 INFO (closeThreadPool-2074-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3e0d4c20: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@1e9a23a3 [junit4] 2> 170055 INFO (closeThreadPool-2074-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@52091add{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0} [junit4] 2> 170055 INFO (closeThreadPool-2074-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3a6e2c88{/,null,STOPPED} [junit4] 2> 170055 INFO (closeThreadPool-2074-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=DistributedQueryComponentCustomSortTest -Dtests.method=test -Dtests.seed=450456CCC465615B -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=no -Dtests.timezone=Asia/Calcutta -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 5.60s J0 | DistributedQueryComponentCustomSortTest.test <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:39455/collection1: org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded:[http://[::1]:4, http://[::1]:8, http://[::1]:6] [junit4] > at __randomizedtesting.SeedInfo.seed([450456CCC465615B:CD5069166A990CA3]: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> 170058 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 170058 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2099127906 [junit4] 2> 170059 INFO (coreCloseExecutor-2092-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@13b10b3e [junit4] 2> 170059 INFO (coreCloseExecutor-2092-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@13b10b3e [junit4] 2> 170059 INFO (coreCloseExecutor-2092-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6554435a: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@67047d49 [junit4] 2> 170063 INFO (coreCloseExecutor-2092-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary). [junit4] 2> 170064 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null [junit4] 2> 170064 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4900723b: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@17f57440 [junit4] 2> 170065 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null [junit4] 2> 170065 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2ce620f: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@1ca72d7f [junit4] 2> 170066 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null [junit4] 2> 170066 INFO (SUITE-DistributedQueryComponentCustomSortTest-seed#[450456CCC465615B]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4d035c40: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@1e9a23a3 [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_450456CCC465615B-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {_root_=BlockTreeOrds(blocksize=128), id=Lucene84, text=FST50, asdfasdf_field_should_match_catchall_dynamic_field_adsfasdf=BlockTreeOrds(blocksize=128)}, docValues:{id_i=DocValuesFormat(name=Asserting), _version_=DocValuesFormat(name=Lucene80), payload=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=288, maxMBSortInHeap=6.578774363242141, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=no, timezone=Asia/Calcutta [junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_291 (64-bit)/cpus=4,threads=1,free=168682952,total=518520832 [junit4] 2> NOTE: All tests run in this JVM: [RAMDirectoryFactoryTest, MoveReplicaHDFSFailoverTest, TestCustomDocTransformer, PreAnalyzedUpdateProcessorTest, TestXmlQParser, TestLRUCache, TestHashQParserPlugin, SolrJmxReporterCloudTest, RecoveryZkTest, CoreAdminHandlerTest, SpatialRPTFieldTypeTest, SearchHandlerTest, TestCloudDeduplication, OutputWriterTest, HdfsCollectionsAPIDistributedZkTest, TestMinimalConfig, ReplaceNodeTest, NotRequiredUniqueKeyTest, HdfsLockFactoryTest, TestHighlightDedupGrouping, TestContainerPlugin, PathHierarchyTokenizerFactoryTest, HdfsRestartWhileUpdatingTest, TestJsonFacetsWithNestedObjects, RankQParserPluginTest, TestDynamicLoadingUrl, BasicZkTest, DistributedQueryComponentCustomSortTest] [junit4] Completed [88/947 (1!)] on J0 in 6.70s, 1 test, 1 error <<< FAILURES! [...truncated 57144 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
