You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2021/09/27 14:13:29 UTC

[JENKINS] Lucene » Lucene-Solr-Tests-8.x - Build # 3251 - Unstable!

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...]

[JENKINS] Lucene » Lucene-Solr-Tests-8.x - Build # 3252 - Still Unstable!

Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.x/3252/

1 tests failed.
FAILED:  org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.test

Error Message:
Error from server at http://127.0.0.1:46793/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:46793/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([C1164DB6073B92B7:4942726CA9C7FF4F]: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 15800 lines...]
   [junit4] Suite: org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest
   [junit4]   2> 2006533 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-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> 2006533 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 2006533 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/data-dir-176-001
   [junit4]   2> 2006533 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=15 numCloses=15
   [junit4]   2> 2006533 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 2006535 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-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> 2006535 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 2006535 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 2006541 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-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> 2006559 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
   [junit4]   2> 2006559 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2006559 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
   [junit4]   2> 2006560 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2006561 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2008348 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-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> 2008362 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-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> 2008363 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 2008378 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@70ef39fd[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2008378 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@70ef39fd[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2008406 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@54bcc859[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2008406 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@54bcc859[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2008407 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-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> 2008432 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38c85db2
   [junit4]   2> 2008438 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38c85db2
   [junit4]   2> 2008439 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38c85db2
   [junit4]   2> 2008440 INFO  (coreLoadExecutor-20902-thread-1) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024
   [junit4]   2> 2008446 INFO  (coreLoadExecutor-20902-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> 2008462 INFO  (coreLoadExecutor-20902-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
   [junit4]   2> 2008462 WARN  (coreLoadExecutor-20902-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2008462 WARN  (coreLoadExecutor-20902-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> 2008463 INFO  (coreLoadExecutor-20902-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2008464 INFO  (coreLoadExecutor-20902-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2008464 INFO  (coreLoadExecutor-20902-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> 2008464 INFO  (coreLoadExecutor-20902-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@38c85db2
   [junit4]   2> 2008464 INFO  (coreLoadExecutor-20902-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/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/data-dir-176-001/]
   [junit4]   2> 2008465 INFO  (coreLoadExecutor-20902-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 2008516 INFO  (coreLoadExecutor-20902-thread-1) [    x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2008516 INFO  (coreLoadExecutor-20902-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2008516 INFO  (coreLoadExecutor-20902-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2008516 INFO  (coreLoadExecutor-20902-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2008517 INFO  (coreLoadExecutor-20902-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=11.703125, floorSegmentMB=0.875, forceMergeDeletesPctAllowed=3.112956361764022, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3632261275280041, deletesPctAllowed=32.41210014477001
   [junit4]   2> 2008519 INFO  (coreLoadExecutor-20902-thread-1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2008533 INFO  (coreLoadExecutor-20902-thread-1) [    x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2009025 INFO  (coreLoadExecutor-20902-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 2009026 INFO  (coreLoadExecutor-20902-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1712079397566021632
   [junit4]   2> 2009027 INFO  (searcherExecutor-20904-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2009027 INFO  (searcherExecutor-20904-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2009027 INFO  (searcherExecutor-20904-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2009027 INFO  (searcherExecutor-20904-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 2009027 INFO  (searcherExecutor-20904-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2009028 INFO  (searcherExecutor-20904-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2009028 INFO  (searcherExecutor-20904-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2009028 INFO  (searcherExecutor-20904-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2009028 INFO  (searcherExecutor-20904-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2009029 INFO  (searcherExecutor-20904-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 2009031 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 2009163 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] 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/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/control/cores/collection1
   [junit4]   2> 2009164 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2009164 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 2009164 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] 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> 2009180 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2009180 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2009180 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 2009182 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@66fe0109{/,null,AVAILABLE}
   [junit4]   2> 2009184 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.AbstractConnector Started ServerConnector@6dbb1ac7{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:42131}
   [junit4]   2> 2009184 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.Server Started @2009212ms
   [junit4]   2> 2009184 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/, hostPort=42131, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/control/cores}
   [junit4]   2> 2009184 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2009184 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2009184 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 8.11.0
   [junit4]   2> 2009184 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 2009184 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
   [junit4]   2> 2009184 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2021-09-27T18:09:09.265Z
   [junit4]   2> 2009184 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] 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/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/control/solr.xml
   [junit4]   2> 2009186 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 2009186 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 2009188 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@38c85db2, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 2009909 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 2009912 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@17cb9f06[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2009912 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@17cb9f06[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2010002 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@d937fac[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2010002 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@d937fac[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2010003 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] 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> 2010126 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4
   [junit4]   2> 2010126 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2010172 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38c85db2
   [junit4]   2> 2010183 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38c85db2
   [junit4]   2> 2010183 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38c85db2
   [junit4]   2> 2010184 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] 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/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/control/cores
   [junit4]   2> 2010184 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2010192 INFO  (coreLoadExecutor-20922-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> 2010215 INFO  (coreLoadExecutor-20922-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
   [junit4]   2> 2010215 WARN  (coreLoadExecutor-20922-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2010215 WARN  (coreLoadExecutor-20922-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> 2010216 INFO  (coreLoadExecutor-20922-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2010217 INFO  (coreLoadExecutor-20922-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2010217 INFO  (coreLoadExecutor-20922-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> 2010217 INFO  (coreLoadExecutor-20922-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@38c85db2
   [junit4]   2> 2010217 INFO  (coreLoadExecutor-20922-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/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/control/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 2010219 INFO  (coreLoadExecutor-20922-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 2010271 INFO  (coreLoadExecutor-20922-thread-1) [    x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2010271 INFO  (coreLoadExecutor-20922-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2010272 INFO  (coreLoadExecutor-20922-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2010272 INFO  (coreLoadExecutor-20922-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2010272 INFO  (coreLoadExecutor-20922-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=11.703125, floorSegmentMB=0.875, forceMergeDeletesPctAllowed=3.112956361764022, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3632261275280041, deletesPctAllowed=32.41210014477001
   [junit4]   2> 2010274 INFO  (coreLoadExecutor-20922-thread-1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2010292 INFO  (coreLoadExecutor-20922-thread-1) [    x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2010377 INFO  (coreLoadExecutor-20922-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 2010377 INFO  (coreLoadExecutor-20922-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1712079398982647808
   [junit4]   2> 2010378 INFO  (searcherExecutor-20924-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2010379 INFO  (searcherExecutor-20924-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2010379 INFO  (searcherExecutor-20924-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2010379 INFO  (searcherExecutor-20924-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 2010379 INFO  (searcherExecutor-20924-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2010379 INFO  (searcherExecutor-20924-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2010379 INFO  (searcherExecutor-20924-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2010379 INFO  (searcherExecutor-20924-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2010379 INFO  (searcherExecutor-20924-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2010381 INFO  (searcherExecutor-20924-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 2010583 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] 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/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 2010583 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2010583 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 2010583 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] 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> 2010584 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2010584 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2010584 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 2010584 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@15401561{/,null,AVAILABLE}
   [junit4]   2> 2010585 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.AbstractConnector Started ServerConnector@3ab1f53b{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:45171}
   [junit4]   2> 2010585 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.Server Started @2010613ms
   [junit4]   2> 2010585 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/, hostPort=45171, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/shard0/cores}
   [junit4]   2> 2010585 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2010585 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2010585 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 8.11.0
   [junit4]   2> 2010585 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 2010585 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
   [junit4]   2> 2010585 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2021-09-27T18:09:10.666Z
   [junit4]   2> 2010585 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] 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/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 2010587 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 2010587 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 2010589 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@38c85db2, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 2011868 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 2011871 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@59566fea[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2011871 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@59566fea[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2011879 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5fe19ecb[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2011879 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5fe19ecb[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2011884 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] 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> 2011917 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4
   [junit4]   2> 2011917 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2011963 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38c85db2
   [junit4]   2> 2012036 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38c85db2
   [junit4]   2> 2012036 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38c85db2
   [junit4]   2> 2012040 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] 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/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/shard0/cores
   [junit4]   2> 2012040 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2012065 INFO  (coreLoadExecutor-20941-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> 2012103 INFO  (coreLoadExecutor-20941-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
   [junit4]   2> 2012103 WARN  (coreLoadExecutor-20941-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2012104 WARN  (coreLoadExecutor-20941-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> 2012105 INFO  (coreLoadExecutor-20941-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2012105 INFO  (coreLoadExecutor-20941-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2012105 INFO  (coreLoadExecutor-20941-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> 2012105 INFO  (coreLoadExecutor-20941-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@38c85db2
   [junit4]   2> 2012105 INFO  (coreLoadExecutor-20941-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/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/shard0/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 2012107 INFO  (coreLoadExecutor-20941-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 2012417 INFO  (coreLoadExecutor-20941-thread-1) [    x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2012417 INFO  (coreLoadExecutor-20941-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2012418 INFO  (coreLoadExecutor-20941-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2012418 INFO  (coreLoadExecutor-20941-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2012418 INFO  (coreLoadExecutor-20941-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=11.703125, floorSegmentMB=0.875, forceMergeDeletesPctAllowed=3.112956361764022, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3632261275280041, deletesPctAllowed=32.41210014477001
   [junit4]   2> 2012420 INFO  (coreLoadExecutor-20941-thread-1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2012434 INFO  (coreLoadExecutor-20941-thread-1) [    x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2012494 INFO  (coreLoadExecutor-20941-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 2012495 INFO  (coreLoadExecutor-20941-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1712079401203531776
   [junit4]   2> 2012496 INFO  (searcherExecutor-20943-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2012496 INFO  (searcherExecutor-20943-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2012496 INFO  (searcherExecutor-20943-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2012496 INFO  (searcherExecutor-20943-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 2012496 INFO  (searcherExecutor-20943-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2012496 INFO  (searcherExecutor-20943-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2012496 INFO  (searcherExecutor-20943-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2012497 INFO  (searcherExecutor-20943-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2012497 INFO  (searcherExecutor-20943-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2012499 INFO  (searcherExecutor-20943-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 2012597 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] 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/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/shard1/cores/collection1
   [junit4]   2> 2012598 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2012598 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 2012598 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] 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> 2012606 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2012606 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2012606 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 2012606 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1e9751ca{/,null,AVAILABLE}
   [junit4]   2> 2012606 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.AbstractConnector Started ServerConnector@32596b4e{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:36645}
   [junit4]   2> 2012606 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.Server Started @2012635ms
   [junit4]   2> 2012606 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/, hostPort=36645, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/shard1/cores}
   [junit4]   2> 2012606 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2012606 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2012607 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 8.11.0
   [junit4]   2> 2012607 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 2012607 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
   [junit4]   2> 2012607 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2021-09-27T18:09:12.688Z
   [junit4]   2> 2012607 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] 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/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/shard1/solr.xml
   [junit4]   2> 2012609 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 2012609 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 2012611 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@38c85db2, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 2013404 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 2013405 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@983408a[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2013405 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@983408a[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2013408 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@11838798[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2013408 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@11838798[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2013409 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] 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> 2013622 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4
   [junit4]   2> 2013622 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2013672 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38c85db2
   [junit4]   2> 2013684 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38c85db2
   [junit4]   2> 2013684 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38c85db2
   [junit4]   2> 2013685 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] 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/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/shard1/cores
   [junit4]   2> 2013685 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2013692 INFO  (coreLoadExecutor-20960-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> 2013721 INFO  (coreLoadExecutor-20960-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
   [junit4]   2> 2013722 WARN  (coreLoadExecutor-20960-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2013722 WARN  (coreLoadExecutor-20960-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> 2013724 INFO  (coreLoadExecutor-20960-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2013724 INFO  (coreLoadExecutor-20960-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2013724 INFO  (coreLoadExecutor-20960-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> 2013724 INFO  (coreLoadExecutor-20960-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@38c85db2
   [junit4]   2> 2013724 INFO  (coreLoadExecutor-20960-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/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/shard1/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/shard1/cores/collection1/data/]
   [junit4]   2> 2013726 INFO  (coreLoadExecutor-20960-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 2013780 INFO  (coreLoadExecutor-20960-thread-1) [    x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2013780 INFO  (coreLoadExecutor-20960-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2013781 INFO  (coreLoadExecutor-20960-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2013781 INFO  (coreLoadExecutor-20960-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2013782 INFO  (coreLoadExecutor-20960-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=11.703125, floorSegmentMB=0.875, forceMergeDeletesPctAllowed=3.112956361764022, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3632261275280041, deletesPctAllowed=32.41210014477001
   [junit4]   2> 2013784 INFO  (coreLoadExecutor-20960-thread-1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2013801 INFO  (coreLoadExecutor-20960-thread-1) [    x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2013889 INFO  (coreLoadExecutor-20960-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 2013889 INFO  (coreLoadExecutor-20960-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1712079402665246720
   [junit4]   2> 2013890 INFO  (searcherExecutor-20962-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2013890 INFO  (searcherExecutor-20962-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2013891 INFO  (searcherExecutor-20962-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2013891 INFO  (searcherExecutor-20962-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 2013891 INFO  (searcherExecutor-20962-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2013891 INFO  (searcherExecutor-20962-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2013891 INFO  (searcherExecutor-20962-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2013891 INFO  (searcherExecutor-20962-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2013891 INFO  (searcherExecutor-20962-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2013894 INFO  (searcherExecutor-20962-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 2014494 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] 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/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/shard2/cores/collection1
   [junit4]   2> 2014495 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2014495 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 2014495 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] 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> 2014495 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2014495 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2014496 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2014498 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@409ed52e{/,null,AVAILABLE}
   [junit4]   2> 2014498 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.AbstractConnector Started ServerConnector@5a6081b6{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:46793}
   [junit4]   2> 2014498 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.s.Server Started @2014526ms
   [junit4]   2> 2014498 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/, hostPort=46793, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/shard2/cores}
   [junit4]   2> 2014498 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2014498 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2014498 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 8.11.0
   [junit4]   2> 2014498 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 2014498 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
   [junit4]   2> 2014498 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2021-09-27T18:09:14.579Z
   [junit4]   2> 2014498 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] 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/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/shard2/solr.xml
   [junit4]   2> 2014501 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 2014501 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 2014502 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@38c85db2, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 2015075 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 2015078 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@39ab892b[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2015078 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@39ab892b[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2015090 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@647105a8[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2015090 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@647105a8[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2015091 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] 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> 2015102 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4
   [junit4]   2> 2015102 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2015120 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38c85db2
   [junit4]   2> 2015131 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38c85db2
   [junit4]   2> 2015131 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38c85db2
   [junit4]   2> 2015133 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] 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/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/shard2/cores
   [junit4]   2> 2015133 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[C1164DB6073B92B7]) [     ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2015142 INFO  (coreLoadExecutor-20979-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> 2015159 INFO  (coreLoadExecutor-20979-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
   [junit4]   2> 2015160 WARN  (coreLoadExecutor-20979-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2015160 WARN  (coreLoadExecutor-20979-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> 2015161 INFO  (coreLoadExecutor-20979-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2015161 INFO  (coreLoadExecutor-20979-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2015161 INFO  (coreLoadExecutor-20979-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> 2015161 INFO  (coreLoadExecutor-20979-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@38c85db2
   [junit4]   2> 2015161 INFO  (coreLoadExecutor-20979-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/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/shard2/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001/tempDir-001/shard2/cores/collection1/data/]
   [junit4]   2> 2015163 INFO  (coreLoadExecutor-20979-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 2015223 INFO  (coreLoadExecutor-20979-thread-1) [    x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2015223 INFO  (coreLoadExecutor-20979-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2015224 INFO  (coreLoadExecutor-20979-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2015224 INFO  (coreLoadExecutor-20979-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2015224 INFO  (coreLoadExecutor-20979-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=11.703125, floorSegmentMB=0.875, forceMergeDeletesPctAllowed=3.112956361764022, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3632261275280041, deletesPctAllowed=32.41210014477001
   [junit4]   2> 2015226 INFO  (coreLoadExecutor-20979-thread-1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2015243 INFO  (coreLoadExecutor-20979-thread-1) [    x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2015311 INFO  (coreLoadExecutor-20979-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 2015311 INFO  (coreLoadExecutor-20979-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1712079404156321792
   [junit4]   2> 2015312 INFO  (searcherExecutor-20981-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2015313 INFO  (searcherExecutor-20981-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2015313 INFO  (searcherExecutor-20981-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2015313 INFO  (searcherExecutor-20981-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: mul

[...truncated too long message...]

ts=true -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   6.62s J3 | DistributedQueryComponentCustomSortTest.test <<<
   [junit4]    > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:46793/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([C1164DB6073B92B7:4942726CA9C7FF4F]: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> 2015651 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 2015651 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2009633916
   [junit4]   2> 2015651 INFO  (coreCloseExecutor-21007-thread-1) [     ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@650a7ac1
   [junit4]   2> 2015651 INFO  (coreCloseExecutor-21007-thread-1) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@650a7ac1
   [junit4]   2> 2015651 INFO  (coreCloseExecutor-21007-thread-1) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@49f3e525: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@470f1d29
   [junit4]   2> 2015654 INFO  (coreCloseExecutor-21007-thread-1) [     ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  ... SKIPPED (unnecessary).
   [junit4]   2> 2015655 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
   [junit4]   2> 2015655 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3098293c: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@9c38cfe
   [junit4]   2> 2015656 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
   [junit4]   2> 2015656 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3a29125: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@6a8ab4af
   [junit4]   2> 2015657 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
   [junit4]   2> 2015657 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[C1164DB6073B92B7]-worker) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@55298a7d: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@5ab240ed
   [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/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_C1164DB6073B92B7-001
   [junit4]   2> NOTE: test params are: codec=Lucene87, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=ar-SD, timezone=America/Virgin
   [junit4]   2> NOTE: Linux 4.15.0-147-generic amd64/Oracle Corporation 1.8.0_291 (64-bit)/cpus=4,threads=1,free=153070272,total=521142272
   [junit4]   2> NOTE: All tests run in this JVM: [TestSimGenericDistributedQueue, SharedFSAutoReplicaFailoverTest, SoftAutoCommitTest, TestAddFieldRealTimeGet, TestGroupingSearch, TestSchemaDesignerSettingsDAO, TestRequestForwarding, SuggesterWFSTTest, XmlUpdateRequestHandlerTest, HdfsTlogReplayBufferedWhileIndexingTest, TestRequestStatusCollectionAPI, DirectSolrSpellCheckerTest, AuthToolTest, TestCloudRecovery2, TestUtils, CdcrWithNodesRestartsTest, NodeLostTriggerTest, TestCloudNestedDocsSort, SystemLogListenerTest, BasicDistributedZkTest, CacheHeaderTest, QueryResultKeyTest, RecoveryAfterSoftCommitTest, CursorPagingTest, TestMacros, MultiSolrCloudTestCaseTest, SolrPluginUtilsTest, TestDistributedGrouping, TestTrie, HdfsRecoveryZkTest, RangeFacetCloudTest, DistributedFacetPivotLongTailTest, OverseerRolesTest, TestSolrJ, TaggingAttributeTest, TestDFRSimilarityFactory, RequestLoggingTest, HdfsCloudIncrementalBackupTest, SolrGraphiteReporterTest, TestLegacyField, WrapperMergePolicyFactoryTest, TestSearcherReuse, TestLeaderElectionZkExpiry, ConnectionManagerTest, DistributedSpellCheckComponentTest, TestManagedSchemaThreadSafety, TestCoreContainer, PeerSyncWithIndexFingerprintCachingTest, BackupRestoreApiErrorConditionsTest, ScriptEngineTest, EmbeddedSolrNoSerializeTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestFieldSortValues, TestValueSourceCache, TestFieldCollectionResource, TestSizeLimitedDistributedMap, ZookeeperReadAPITest, InfoHandlerTest, TestBulkSchemaAPI, ZkShardTermsTest, TestNumericTerms64, HighlighterMaxOffsetTest, TestAuthenticationFramework, AnalyticsQueryTest, DistributedQueueTest, ForceLeaderWithTlogReplicasTest, TlogReplayBufferedWhileIndexingTest, TestStressLucene, TestConfigsApi, TestSnapshotCloudManager, ManagedSchemaRoundRobinCloudTest, TemplateUpdateProcessorTest, ParsingFieldUpdateProcessorsTest, LegacyCloudClusterPropTest, UpdateLogTest, SolrCloudReportersTest, UUIDUpdateProcessorFallbackTest, FullHLLTest, CdcrOpsAndBoundariesTest, ResponseHeaderTest, MissingSegmentRecoveryTest, CoreAdminCreateDiscoverTest, TestImpersonationWithHadoopAuth, DistributedMLTComponentTest, TestOverriddenPrefixQueryForCustomFieldType, TestSolrCLIRunExample, BlockJoinFacetRandomTest, SOLR749Test, HdfsChaosMonkeyNothingIsSafeTest, PolyFieldTest, TestStreamBody, TestLegacyBM25SimilarityFactory, DateRangeFieldTest, HdfsBackupRepositoryIntegrationTest, ScheduledTriggerIntegrationTest, ImplicitSnitchTest, TestClusterStateMutator, TestSchemaNameResource, TestMinHashQParser, TestIndexingPerformance, HdfsDirectoryTest, QueryElevationComponentTest, CloudExitableDirectoryReaderTest, TestStressThreadBackup, SaslZkACLProviderTest, TestRestoreCore, MultiDestinationAuditLoggerTest, TestCollectionsAPIViaSolrCloudCluster, TestRangeQuery, DistanceUnitsTest, SignificantTermsQParserPluginTest, TestDistribPackageStore, TestSimPolicyCloud, SortByFunctionTest, TestCollationFieldDocValues, TestCursorMarkWithoutUniqueKey, TestSimpleTrackingShardHandler, TestSolrCoreProperties, TestTolerantSearch, TestDeprecatedFilters, TestLuceneMatchVersion, TestReversedWildcardFilterFactory, TestWordDelimiterFilterFactory, TokenizerChainTest, TestLuceneIndexBackCompat, ClusterStateMockUtilTest, ClusterStateTest, ClusterStateUpdateTest, CollectionPropsTest, DeleteNodeTest, DeleteStatusTest, DistribDocExpirationUpdateProcessorTest, NestedShardedAtomicUpdateTest, NodeMutatorTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverseerTaskQueueTest, ReindexCollectionTest, TestDownShardTolerantSearch, TestOnReconnectListenerSupport, TestPullReplicaErrorHandling, TestStressLiveNodes, AutoAddReplicasPlanActionTest, ExecutePlanActionTest, IndexSizeTriggerSizeEstimationTest, MetricTriggerTest, NodeAddedTriggerTest, ScheduledTriggerTest, TestPolicyCloud, TestSimDistributedQueue, TestSimTriggerIntegration, HdfsBasicDistributedZk2Test, HdfsBasicDistributedZkTest, HdfsChaosMonkeySafeLeaderTest, HdfsNNFailoverTest, HdfsRestartWhileUpdatingTest, HdfsSyncSliceTest, HdfsThreadLeakTest, RuleEngineTest, RulesTest, HdfsDirectoryFactoryTest, TestConfigOverlay, TestConfigSetImmutable, TestConfigSets, TestCustomStream, TestDynamicURP, TestQuerySenderNoQuery, TestReloadAndDeleteDocs, TestSimpleTextCodec, TestSolrXml, TestXIncludeConfig, BackupIdTest, TestSolrCloudSnapshots, TestIncrementalCoreBackup, TestReplicationHandlerBackup, TestReqParamsAPI, TestSolrConfigHandlerCloud, V2StandaloneTest, CoreMergeIndexesAdminHandlerTest, DaemonStreamApiTest, MetricsHandlerTest, MetricsHistoryHandlerTest, DistributedQueryComponentCustomSortTest]
   [junit4] Completed [705/947 (1!)] on J3 in 9.31s, 1 test, 1 error <<< FAILURES!

[...truncated 54989 lines...]