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/08/15 08:43:37 UTC

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

Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.x/2995/

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

Error Message:
Number of documents (1) is different from number of expected values (3

Stack Trace:
java.lang.AssertionError: Number of documents (1) is different from number of expected values (3
	at __randomizedtesting.SeedInfo.seed([E90AB98214B95F40:615E8658BA4532B8]:0)
	at org.junit.Assert.fail(Assert.java:89)
	at org.apache.solr.SolrTestCaseJ4.assertFieldValues(SolrTestCaseJ4.java:2184)
	at org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.test(DistributedQueryComponentCustomSortTest.java:128)
	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 15725 lines...]
   [junit4] Suite: org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest
   [junit4]   2> 2043407 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-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> 2043407 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-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_E90AB98214B95F40-001/data-dir-253-001
   [junit4]   2> 2043407 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=33 numCloses=33
   [junit4]   2> 2043408 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 2043409 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-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> 2043409 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 2043409 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_/vf
   [junit4]   2> 2043409 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 2043415 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-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> 2043430 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.0
   [junit4]   2> 2043430 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2043430 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
   [junit4]   2> 2043432 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2043432 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2044780 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-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:812) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:802) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:663) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:652) [java/:?]
   [junit4]   2> 	at org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.setUpBeforeClass(DistributedQueryComponentCustomSortTest.java:41) [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> 2044781 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-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:812) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:802) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:663) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:652) [java/:?]
   [junit4]   2> 	at org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.setUpBeforeClass(DistributedQueryComponentCustomSortTest.java:41) [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> 2044782 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 2044793 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6b9bf587[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2044793 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6b9bf587[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2044822 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5105ad5e[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2044822 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5105ad5e[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2044824 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-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> 2044875 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b16eb35
   [junit4]   2> 2044881 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b16eb35
   [junit4]   2> 2044882 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b16eb35
   [junit4]   2> 2044883 INFO  (coreLoadExecutor-20094-thread-1) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024
   [junit4]   2> 2044896 INFO  (coreLoadExecutor-20094-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> 2044913 INFO  (coreLoadExecutor-20094-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.0
   [junit4]   2> 2044913 WARN  (coreLoadExecutor-20094-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2044914 WARN  (coreLoadExecutor-20094-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> 2044915 INFO  (coreLoadExecutor-20094-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2044915 INFO  (coreLoadExecutor-20094-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2044915 INFO  (coreLoadExecutor-20094-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> 2044916 INFO  (coreLoadExecutor-20094-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@2b16eb35
   [junit4]   2> 2044916 INFO  (coreLoadExecutor-20094-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_E90AB98214B95F40-001/data-dir-253-001/]
   [junit4]   2> 2044917 INFO  (coreLoadExecutor-20094-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=0.779296875, noCFSRatio=0.0]
   [junit4]   2> 2045004 INFO  (coreLoadExecutor-20094-thread-1) [    x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2045004 INFO  (coreLoadExecutor-20094-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2045005 INFO  (coreLoadExecutor-20094-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2045005 INFO  (coreLoadExecutor-20094-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2045005 INFO  (coreLoadExecutor-20094-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=36.96875, floorSegmentMB=0.7158203125, forceMergeDeletesPctAllowed=18.36852978616445, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2640056466203309, deletesPctAllowed=34.80794751556872
   [junit4]   2> 2045006 INFO  (coreLoadExecutor-20094-thread-1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2045022 INFO  (coreLoadExecutor-20094-thread-1) [    x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2045089 INFO  (coreLoadExecutor-20094-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 2045089 INFO  (coreLoadExecutor-20094-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1708146506930847744
   [junit4]   2> 2045090 INFO  (searcherExecutor-20096-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2045090 INFO  (searcherExecutor-20096-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2045090 INFO  (searcherExecutor-20096-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2045090 INFO  (searcherExecutor-20096-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 2045090 INFO  (searcherExecutor-20096-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2045090 INFO  (searcherExecutor-20096-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2045091 INFO  (searcherExecutor-20096-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2045091 INFO  (searcherExecutor-20096-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2045091 INFO  (searcherExecutor-20096-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2045092 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 2045092 INFO  (searcherExecutor-20096-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 2045234 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] 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_E90AB98214B95F40-001/tempDir-001/control/cores/collection1
   [junit4]   2> 2045235 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2045235 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 2045235 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] 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> 2045248 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2045249 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2045249 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 2045257 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@56bfbe3f{/_/vf,null,AVAILABLE}
   [junit4]   2> 2045257 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.AbstractConnector Started ServerConnector@1e80e85c{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:35831}
   [junit4]   2> 2045257 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.Server Started @2045426ms
   [junit4]   2> 2045257 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/_/vf, hostPort=35831, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_E90AB98214B95F40-001/tempDir-001/control/cores}
   [junit4]   2> 2045257 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2045257 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2045257 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 8.10.0
   [junit4]   2> 2045257 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 2045257 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
   [junit4]   2> 2045257 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2021-08-15T08:17:32.487Z
   [junit4]   2> 2045257 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] 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_E90AB98214B95F40-001/tempDir-001/control/solr.xml
   [junit4]   2> 2045260 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 2045260 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 2045261 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2b16eb35, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 2046202 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 2046202 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@39517e20[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2046202 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@39517e20[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2046209 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7948a65b[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2046209 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7948a65b[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2046210 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] 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> 2046221 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4
   [junit4]   2> 2046222 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2046242 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b16eb35
   [junit4]   2> 2046250 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b16eb35
   [junit4]   2> 2046250 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b16eb35
   [junit4]   2> 2046252 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] 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_E90AB98214B95F40-001/tempDir-001/control/cores
   [junit4]   2> 2046252 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2046259 INFO  (coreLoadExecutor-20114-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> 2046276 INFO  (coreLoadExecutor-20114-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.0
   [junit4]   2> 2046276 WARN  (coreLoadExecutor-20114-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2046277 WARN  (coreLoadExecutor-20114-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> 2046278 INFO  (coreLoadExecutor-20114-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2046279 INFO  (coreLoadExecutor-20114-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2046279 INFO  (coreLoadExecutor-20114-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> 2046279 INFO  (coreLoadExecutor-20114-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@2b16eb35
   [junit4]   2> 2046279 INFO  (coreLoadExecutor-20114-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_E90AB98214B95F40-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_E90AB98214B95F40-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 2046280 INFO  (coreLoadExecutor-20114-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=0.779296875, noCFSRatio=0.0]
   [junit4]   2> 2046395 INFO  (coreLoadExecutor-20114-thread-1) [    x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2046395 INFO  (coreLoadExecutor-20114-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2046396 INFO  (coreLoadExecutor-20114-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2046397 INFO  (coreLoadExecutor-20114-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2046397 INFO  (coreLoadExecutor-20114-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=36.96875, floorSegmentMB=0.7158203125, forceMergeDeletesPctAllowed=18.36852978616445, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2640056466203309, deletesPctAllowed=34.80794751556872
   [junit4]   2> 2046398 INFO  (coreLoadExecutor-20114-thread-1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2046414 INFO  (coreLoadExecutor-20114-thread-1) [    x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2046487 INFO  (coreLoadExecutor-20114-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 2046487 INFO  (coreLoadExecutor-20114-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1708146508396756992
   [junit4]   2> 2046488 INFO  (searcherExecutor-20116-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2046488 INFO  (searcherExecutor-20116-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2046488 INFO  (searcherExecutor-20116-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2046488 INFO  (searcherExecutor-20116-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 2046489 INFO  (searcherExecutor-20116-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2046489 INFO  (searcherExecutor-20116-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2046489 INFO  (searcherExecutor-20116-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2046489 INFO  (searcherExecutor-20116-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2046489 INFO  (searcherExecutor-20116-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2046491 INFO  (searcherExecutor-20116-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 2047090 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] 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_E90AB98214B95F40-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 2047091 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2047091 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 2047091 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] 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> 2047093 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2047093 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2047093 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 2047094 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2136a2f8{/_/vf,null,AVAILABLE}
   [junit4]   2> 2047095 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.AbstractConnector Started ServerConnector@552fc18f{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:41991}
   [junit4]   2> 2047095 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.Server Started @2047264ms
   [junit4]   2> 2047095 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/_/vf, hostPort=41991, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_E90AB98214B95F40-001/tempDir-001/shard0/cores}
   [junit4]   2> 2047095 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2047095 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2047095 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 8.10.0
   [junit4]   2> 2047095 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 2047095 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
   [junit4]   2> 2047095 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2021-08-15T08:17:34.325Z
   [junit4]   2> 2047095 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] 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_E90AB98214B95F40-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 2047097 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 2047097 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 2047099 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2b16eb35, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 2047590 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 2047590 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7837f31a[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2047590 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7837f31a[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2047595 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1e21d3a4[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2047595 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1e21d3a4[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2047596 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] 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> 2047607 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4
   [junit4]   2> 2047607 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2047733 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b16eb35
   [junit4]   2> 2047746 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b16eb35
   [junit4]   2> 2047746 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b16eb35
   [junit4]   2> 2047748 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] 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_E90AB98214B95F40-001/tempDir-001/shard0/cores
   [junit4]   2> 2047748 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2047761 INFO  (coreLoadExecutor-20133-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> 2047784 INFO  (coreLoadExecutor-20133-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.0
   [junit4]   2> 2047785 WARN  (coreLoadExecutor-20133-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2047786 WARN  (coreLoadExecutor-20133-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> 2047787 INFO  (coreLoadExecutor-20133-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2047788 INFO  (coreLoadExecutor-20133-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2047788 INFO  (coreLoadExecutor-20133-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> 2047788 INFO  (coreLoadExecutor-20133-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@2b16eb35
   [junit4]   2> 2047788 INFO  (coreLoadExecutor-20133-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_E90AB98214B95F40-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_E90AB98214B95F40-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 2047790 INFO  (coreLoadExecutor-20133-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=0.779296875, noCFSRatio=0.0]
   [junit4]   2> 2047844 INFO  (coreLoadExecutor-20133-thread-1) [    x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2047844 INFO  (coreLoadExecutor-20133-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2047845 INFO  (coreLoadExecutor-20133-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2047845 INFO  (coreLoadExecutor-20133-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2047846 INFO  (coreLoadExecutor-20133-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=36.96875, floorSegmentMB=0.7158203125, forceMergeDeletesPctAllowed=18.36852978616445, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2640056466203309, deletesPctAllowed=34.80794751556872
   [junit4]   2> 2047847 INFO  (coreLoadExecutor-20133-thread-1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2047861 INFO  (coreLoadExecutor-20133-thread-1) [    x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2048042 INFO  (coreLoadExecutor-20133-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 2048042 INFO  (coreLoadExecutor-20133-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1708146510027292672
   [junit4]   2> 2048043 INFO  (searcherExecutor-20135-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2048044 INFO  (searcherExecutor-20135-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2048044 INFO  (searcherExecutor-20135-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2048044 INFO  (searcherExecutor-20135-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 2048044 INFO  (searcherExecutor-20135-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2048044 INFO  (searcherExecutor-20135-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2048044 INFO  (searcherExecutor-20135-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2048045 INFO  (searcherExecutor-20135-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2048045 INFO  (searcherExecutor-20135-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2048047 INFO  (searcherExecutor-20135-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 2048151 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] 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_E90AB98214B95F40-001/tempDir-001/shard1/cores/collection1
   [junit4]   2> 2048152 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2048152 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 2048152 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] 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> 2048160 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2048160 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2048160 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2048160 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@130136db{/_/vf,null,AVAILABLE}
   [junit4]   2> 2048161 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.AbstractConnector Started ServerConnector@5d853e64{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:41043}
   [junit4]   2> 2048161 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.Server Started @2048330ms
   [junit4]   2> 2048161 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/_/vf, hostPort=41043, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_E90AB98214B95F40-001/tempDir-001/shard1/cores}
   [junit4]   2> 2048161 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2048161 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2048161 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 8.10.0
   [junit4]   2> 2048161 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 2048161 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
   [junit4]   2> 2048161 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2021-08-15T08:17:35.391Z
   [junit4]   2> 2048161 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] 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_E90AB98214B95F40-001/tempDir-001/shard1/solr.xml
   [junit4]   2> 2048164 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 2048164 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 2048165 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2b16eb35, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 2049320 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 2049321 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3ec3d411[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2049321 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3ec3d411[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2049325 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5b22ae4[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2049325 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5b22ae4[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2049327 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] 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> 2049529 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4
   [junit4]   2> 2049529 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2049554 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b16eb35
   [junit4]   2> 2049566 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b16eb35
   [junit4]   2> 2049566 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b16eb35
   [junit4]   2> 2049567 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] 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_E90AB98214B95F40-001/tempDir-001/shard1/cores
   [junit4]   2> 2049567 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2049577 INFO  (coreLoadExecutor-20152-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> 2049595 INFO  (coreLoadExecutor-20152-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.0
   [junit4]   2> 2049595 WARN  (coreLoadExecutor-20152-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2049596 WARN  (coreLoadExecutor-20152-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> 2049597 INFO  (coreLoadExecutor-20152-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2049598 INFO  (coreLoadExecutor-20152-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2049598 INFO  (coreLoadExecutor-20152-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> 2049598 INFO  (coreLoadExecutor-20152-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@2b16eb35
   [junit4]   2> 2049598 INFO  (coreLoadExecutor-20152-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_E90AB98214B95F40-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_E90AB98214B95F40-001/tempDir-001/shard1/cores/collection1/data/]
   [junit4]   2> 2049605 INFO  (coreLoadExecutor-20152-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=0.779296875, noCFSRatio=0.0]
   [junit4]   2> 2049666 INFO  (coreLoadExecutor-20152-thread-1) [    x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2049666 INFO  (coreLoadExecutor-20152-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2049667 INFO  (coreLoadExecutor-20152-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2049667 INFO  (coreLoadExecutor-20152-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2049668 INFO  (coreLoadExecutor-20152-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=36.96875, floorSegmentMB=0.7158203125, forceMergeDeletesPctAllowed=18.36852978616445, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2640056466203309, deletesPctAllowed=34.80794751556872
   [junit4]   2> 2049669 INFO  (coreLoadExecutor-20152-thread-1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2049686 INFO  (coreLoadExecutor-20152-thread-1) [    x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2049766 INFO  (coreLoadExecutor-20152-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 2049766 INFO  (coreLoadExecutor-20152-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1708146511835037696
   [junit4]   2> 2049767 INFO  (searcherExecutor-20154-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2049767 INFO  (searcherExecutor-20154-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2049767 INFO  (searcherExecutor-20154-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2049767 INFO  (searcherExecutor-20154-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 2049767 INFO  (searcherExecutor-20154-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2049768 INFO  (searcherExecutor-20154-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2049768 INFO  (searcherExecutor-20154-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2049768 INFO  (searcherExecutor-20154-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2049768 INFO  (searcherExecutor-20154-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2049770 INFO  (searcherExecutor-20154-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 2049892 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] 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_E90AB98214B95F40-001/tempDir-001/shard2/cores/collection1
   [junit4]   2> 2049893 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2049893 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 2049893 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] 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> 2049894 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2049894 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2049894 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2049894 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5641fe9{/_/vf,null,AVAILABLE}
   [junit4]   2> 2049895 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.AbstractConnector Started ServerConnector@f91bb06{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:45099}
   [junit4]   2> 2049895 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.s.Server Started @2050064ms
   [junit4]   2> 2049895 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/_/vf, hostPort=45099, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_E90AB98214B95F40-001/tempDir-001/shard2/cores}
   [junit4]   2> 2049895 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2049895 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2049895 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 8.10.0
   [junit4]   2> 2049895 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 2049895 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
   [junit4]   2> 2049895 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2021-08-15T08:17:37.125Z
   [junit4]   2> 2049895 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] 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_E90AB98214B95F40-001/tempDir-001/shard2/solr.xml
   [junit4]   2> 2049897 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 2049897 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 2049899 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2b16eb35, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 2050283 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 2050284 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@70a955a3[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2050284 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@70a955a3[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2050286 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@177fa3aa[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2050286 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@177fa3aa[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2050288 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] 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> 2050299 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4
   [junit4]   2> 2050299 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2050522 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b16eb35
   [junit4]   2> 2050534 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b16eb35
   [junit4]   2> 2050534 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b16eb35
   [junit4]   2> 2050535 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] 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_E90AB98214B95F40-001/tempDir-001/shard2/cores
   [junit4]   2> 2050535 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[E90AB98214B95F40]) [     ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2050544 INFO  (coreLoadExecutor-20171-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> 2050560 INFO  (coreLoadExecutor-20171-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.0
   [junit4]   2> 2050560 WARN  (coreLoadExecutor-20171-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2050573 WARN  (coreLoadExecutor-20171-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> 2050574 INFO  (coreLoadExecutor-20171-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2050575 INFO  (coreLoadExecutor-20171-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2050575 INFO  (coreLoadExecutor-20171-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> 2050575 INFO  (coreLoadExecutor-20171-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@2b16eb35
   [junit4]   2> 2050575 INFO  (coreLoadExecutor-20171-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_E90AB98214B95F40-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_E90AB98214B95F40-001/tempDir-001/shard2/cores/collection1/data/]
   [junit4]   2> 2050593 INFO  (coreLoadExecutor-20171-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=0.779296875, noCFSRatio=0.0]
   [junit4]   2> 2050644 INFO  (coreLoadExecutor-20171-thread-1) [    x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2050644 INFO  (coreLoadExecutor-20171-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2050645 INFO  (coreLoadExecutor-20171-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2050645 INFO  (coreLoadExecutor-20171-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2050646 INFO  (coreLoadExecutor-20171-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=36.96875, floorSegmentMB=0.7158203125, forceMergeDeletesPctAllowed=18.36852978616445, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2640056466203309, deletesPctAllowed=34.80794751556872
   [junit4]   2> 2050647 INFO  (coreLoadExecutor-20171-thread-1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2050662 INFO  (coreLoadExecutor-20171-thread-1) [    x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2050861 INFO  (coreLoadExecutor-20171-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 2050862 INFO  (coreLoadExecutor-20171-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1708146512984276992
   [junit4]   2> 2050863 INFO  (searcherExecutor-20173-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2050863 INFO  (searcherExecutor-20173-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2050863 INFO  (searcherExecutor-20173-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2050863 INFO  (searcherExecutor-20173-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 2050863 INFO  (searcherExecutor-20173-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2050864 INFO  (searcherExecutor-20173-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2050944 INFO  (searcherExecutor-20173-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2050944 INFO  (searcherExecutor-20173-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2050944 INFO  (searcherExecutor-20173-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for 

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

1571 INFO  (closeThreadPool-20181-thread-8) [     ] o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=DistributedQueryComponentCustomSortTest -Dtests.method=test -Dtests.seed=E90AB98214B95F40 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=en-GB -Dtests.timezone=Australia/Tasmania -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] FAILURE 6.48s J0 | DistributedQueryComponentCustomSortTest.test <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Number of documents (1) is different from number of expected values (3
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([E90AB98214B95F40:615E8658BA4532B8]:0)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertFieldValues(SolrTestCaseJ4.java:2184)
   [junit4]    > 	at org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.test(DistributedQueryComponentCustomSortTest.java:128)
   [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> 2051573 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 2051574 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1717215404
   [junit4]   2> 2051574 INFO  (coreCloseExecutor-20199-thread-1) [     ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1fbd5bc5
   [junit4]   2> 2051574 INFO  (coreCloseExecutor-20199-thread-1) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@1fbd5bc5
   [junit4]   2> 2051574 INFO  (coreCloseExecutor-20199-thread-1) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@362c5f0: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@248406c7
   [junit4]   2> 2051577 INFO  (coreCloseExecutor-20199-thread-1) [     ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  ... SKIPPED (unnecessary).
   [junit4]   2> 2051579 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
   [junit4]   2> 2051579 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@23d023cc: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@29766db6
   [junit4]   2> 2051580 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
   [junit4]   2> 2051580 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@50fbf07b: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@45cbed73
   [junit4]   2> 2051580 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
   [junit4]   2> 2051580 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[E90AB98214B95F40]-worker) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@73e8dd01: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@744517bd
   [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_E90AB98214B95F40-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene87): {id_i=PostingsFormat(name=MockRandom), _version_=BlockTreeOrds(blocksize=128), _root_=BlockTreeOrds(blocksize=128), id=PostingsFormat(name=LuceneVarGapFixedInterval), text=PostingsFormat(name=MockRandom), asdfasdf_field_should_match_catchall_dynamic_field_adsfasdf=BlockTreeOrds(blocksize=128)}, docValues:{payload=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=208, maxMBSortInHeap=7.288885953489112, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=en-GB, timezone=Australia/Tasmania
   [junit4]   2> NOTE: Linux 4.15.0-147-generic amd64/Oracle Corporation 1.8.0_291 (64-bit)/cpus=4,threads=1,free=193282584,total=526385152
   [junit4]   2> NOTE: All tests run in this JVM: [TestManagedSchema, MergeStrategyTest, RAMDirectoryFactoryTest, SolrLogAuditLoggerPluginTest, HdfsUnloadDistributedZkTest, TestManagedResourceStorage, HdfsChaosMonkeySafeLeaderTest, AnalysisAfterCoreReloadTest, SolrCoreCheckLockOnStartupTest, TestExclusionRuleCollectionAccess, TestLockTree, TestLRUStatsCache, TestCloudJSONFacetJoinDomain, SchemaApiFailureTest, BasicAuthOnSingleNodeTest, TestConfigSetImmutable, TestCloudSchemaless, TestComponentsName, ResponseLogComponentTest, TestXIncludeConfig, BlobRepositoryCloudTest, FieldMutatingUpdateProcessorTest, NodeAddedTriggerIntegrationTest, TestReloadDeadlock, SolrIndexMetricsTest, HdfsRestartWhileUpdatingTest, NumberUtilsTest, TestSimDistributedQueue, TestFieldTypeCollectionResource, TestMinMaxOnMultiValuedField, SpellingQueryConverterTest, ExitableDirectoryReaderTest, MultiThreadedOCPTest, TestFastOutputStream, RouteFieldTest, SimpleFacetsTest, FullSolrCloudDistribCmdsTest, NodeMarkersRegistrationTest, TestSSLRandomization, TriLevelCompositeIdRoutingTest, ClusterStateMockUtilTest, TestFuzzyAnalyzedSuggestions, TestLMDirichletSimilarityFactory, BlockDirectoryTest, CurrencyFieldTypeTest, TestLegacyFieldCache, TestPointFields, TestCloudConsistency, StatelessScriptUpdateProcessorFactoryTest, HighlighterWithoutStoredIdTest, TestPolicyCloud, TestSQLHandler, TestAnalyzedSuggestions, TestSimLargeCluster, TestFiltering, IndexSizeTriggerSizeEstimationTest, SolrLogPostToolTest, ZkSolrClientTest, TestSimpleTrackingShardHandler, SynonymTokenizerTest, TestSha256AuthenticationProvider, CrossCollectionJoinQueryTest, BaseCdcrDistributedZkTest, TestFieldCacheSort, NestedAtomicUpdateTest, LeaderFailureAfterFreshStartTest, TestContainerPlugin, RecoveryZkTest, SimpleCollectionCreateDeleteTest, TestStressCloudBlindAtomicUpdates, BinaryUpdateRequestHandlerTest, TransactionLogTest, TestWaitForStateWithJettyShutdowns, MetricsConfigTest, TestMultiWordSynonyms, DeleteNodeTest, TestMinimalConfig, PathHierarchyTokenizerFactoryTest, TestHdfsUpdateLog, ConcurrentCreateCollectionTest, RootFieldTest, JavabinLoaderTest, DocValuesMultiTest, TestSortableTextField, OverseerTest, TestSolrConfigHandlerCloud, TestSlowCompositeReaderWrapper, XsltUpdateRequestHandlerTest, TestSolrDeletionPolicy1, TestJettySolrRunner, TestDistributedMissingSort, ZkCollectionPropsCachingTest, TestCloudSearcherWarming, TestHttpServletCarrier, TestRandomFlRTGCloud, BadIndexSchemaTest, TestLuceneMatchVersion, StatsComponentTest, SearchHandlerTest, BasicZkTest, CdcrReplicationHandlerTest, MetricsHistoryIntegrationTest, MoveReplicaHDFSFailoverTest, TestLegacyNumericUtils, CollectionReloadTest, DistributedSuggestComponentTest, TestNamedUpdateProcessors, TestXmlQParserPlugin, TestCaffeineCache, TestTermsQParserPlugin, TestStressReorder, PeerSyncWithBufferUpdatesTest, TestCodecSupport, Tagger2Test, JWTIssuerConfigTest, TestSolrCoreParser, TestScoreJoinQPNoScore, TestEmbeddedSolrServerAdminHandler, TestEmbeddedSolrServerConstructors, TestEmbeddedSolrServerSchemaAPI, ConnectionReuseTest, ActionThrottleTest, AddReplicaTest, AliasIntegrationTest, ChaosMonkeyNothingIsSafeTest, ChaosMonkeySafeLeaderTest, ClusterStateTest, ClusterStateUpdateTest, CollectionPropsTest, CollectionStateFormat2Test, CreateRoutedAliasTest, DeleteLastCustomShardedReplicaTest, DistribCursorPagingTest, LeaderElectionContextKeyTest, LeaderVoteWaitTimeoutTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverseerSolrResponseTest, OverseerSolrResponseUnsafeSerializationTest, OverseerStatusTest, OverseerTaskQueueTest, RecoveryAfterSoftCommitTest, ShardRoutingCustomTest, SyncSliceTest, TestCloudRecovery2, TestOnReconnectListenerSupport, TestSkipOverseerOperations, TestTolerantUpdateProcessorCloud, TestUtilizeNode, ZkNodePropsTest, ZkShardTermsTest, HdfsCloudIncrementalBackupTest, TestCollectionsAPIViaSolrCloudCluster, AutoAddReplicasPlanActionTest, ExecutePlanActionTest, HdfsAutoAddReplicasIntegrationTest, HttpTriggerListenerTest, IndexSizeTriggerTest, MetricTriggerIntegrationTest, RestoreTriggerStateTest, ScheduledTriggerTest, TriggerSetPropertiesIntegrationTest, TestSimComputePlanAction, TestSimDistribStateManager, TestSimScenario, CdcrWithNodesRestartsTest, HDFSCollectionsAPITest, QueryResultKeyTest, TestCorePropertiesReload, TestCustomStream, TestMergePolicyConfig, TestSolrConfigHandler, TestReplicationHandlerDiskOverFlow, TestRestoreCore, TestSQLHandlerNonCloud, V2ApiIntegrationTest, AdminHandlersProxyTest, CoreAdminOperationTest, CoreAdminRequestStatusTest, CoreMergeIndexesAdminHandlerTest, LoggingHandlerTest, MBeansHandlerTest, DebugComponentTest, DistributedDebugComponentTest, DistributedExpandComponentTest, DistributedQueryComponentCustomSortTest]
   [junit4] Completed [689/945 (1!)] on J0 in 8.38s, 1 test, 1 failure <<< FAILURES!

[...truncated 53385 lines...]

[JENKINS] Lucene » Lucene-Solr-Tests-8.x - Build # 2996 - 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/2996/

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

Error Message:
Number of documents (1) is different from number of expected values (3

Stack Trace:
java.lang.AssertionError: Number of documents (1) is different from number of expected values (3
	at __randomizedtesting.SeedInfo.seed([3FEB147662B14FEE:B7BF2BACCC4D2216]:0)
	at org.junit.Assert.fail(Assert.java:89)
	at org.apache.solr.SolrTestCaseJ4.assertFieldValues(SolrTestCaseJ4.java:2184)
	at org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.test(DistributedQueryComponentCustomSortTest.java:128)
	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 15807 lines...]
   [junit4] Suite: org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest
   [junit4]   2> 2084727 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-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> 2084728 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-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_3FEB147662B14FEE-001/data-dir-165-001
   [junit4]   2> 2084728 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
   [junit4]   2> 2084728 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 2084729 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 2084729 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 2084730 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /rc_/n
   [junit4]   2> 2084730 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 2084736 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-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> 2084750 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.0
   [junit4]   2> 2084750 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2084750 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
   [junit4]   2> 2084751 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2084752 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2085566 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-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:812) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:802) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:663) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:652) [java/:?]
   [junit4]   2> 	at org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.setUpBeforeClass(DistributedQueryComponentCustomSortTest.java:41) [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> 2085567 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-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:812) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:802) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:663) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:652) [java/:?]
   [junit4]   2> 	at org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.setUpBeforeClass(DistributedQueryComponentCustomSortTest.java:41) [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> 2085569 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 2085569 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5ddb512b[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2085569 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5ddb512b[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2085572 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5544a2e9[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2085572 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5544a2e9[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2085573 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-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> 2085598 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52e5e513
   [junit4]   2> 2085604 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52e5e513
   [junit4]   2> 2085605 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52e5e513
   [junit4]   2> 2085606 INFO  (coreLoadExecutor-21216-thread-1) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024
   [junit4]   2> 2085619 INFO  (coreLoadExecutor-21216-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> 2085635 INFO  (coreLoadExecutor-21216-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.0
   [junit4]   2> 2085635 WARN  (coreLoadExecutor-21216-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2085635 WARN  (coreLoadExecutor-21216-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> 2085636 INFO  (coreLoadExecutor-21216-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2085637 INFO  (coreLoadExecutor-21216-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2085637 INFO  (coreLoadExecutor-21216-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> 2085637 INFO  (coreLoadExecutor-21216-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@52e5e513
   [junit4]   2> 2085637 INFO  (coreLoadExecutor-21216-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_3FEB147662B14FEE-001/data-dir-165-001/]
   [junit4]   2> 2085638 INFO  (coreLoadExecutor-21216-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=48, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4638735152072154]
   [junit4]   2> 2085696 INFO  (coreLoadExecutor-21216-thread-1) [    x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2085696 INFO  (coreLoadExecutor-21216-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2085696 INFO  (coreLoadExecutor-21216-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2085696 INFO  (coreLoadExecutor-21216-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2085697 INFO  (coreLoadExecutor-21216-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=23, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 2085698 INFO  (coreLoadExecutor-21216-thread-1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2085713 INFO  (coreLoadExecutor-21216-thread-1) [    x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2085773 INFO  (coreLoadExecutor-21216-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 2085774 INFO  (coreLoadExecutor-21216-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1708158355410780160
   [junit4]   2> 2085775 INFO  (searcherExecutor-21218-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2085777 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 2085780 INFO  (searcherExecutor-21218-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2085780 INFO  (searcherExecutor-21218-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2085780 INFO  (searcherExecutor-21218-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 2085780 INFO  (searcherExecutor-21218-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2085780 INFO  (searcherExecutor-21218-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2085780 INFO  (searcherExecutor-21218-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2085780 INFO  (searcherExecutor-21218-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2085780 INFO  (searcherExecutor-21218-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2085782 INFO  (searcherExecutor-21218-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 2086003 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] 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_3FEB147662B14FEE-001/tempDir-001/control/cores/collection1
   [junit4]   2> 2086004 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2086004 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 2086004 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] 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> 2086255 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2086255 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2086255 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 2086269 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6ad8a39f{/rc_/n,null,AVAILABLE}
   [junit4]   2> 2086269 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.AbstractConnector Started ServerConnector@2c5f6448{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:36355}
   [junit4]   2> 2086269 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.Server Started @2086298ms
   [junit4]   2> 2086269 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/rc_/n, hostPort=36355, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FEB147662B14FEE-001/tempDir-001/control/cores}
   [junit4]   2> 2086270 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2086270 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2086270 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 8.10.0
   [junit4]   2> 2086270 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 2086270 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
   [junit4]   2> 2086270 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2021-08-15T11:25:52.406Z
   [junit4]   2> 2086270 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] 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_3FEB147662B14FEE-001/tempDir-001/control/solr.xml
   [junit4]   2> 2086272 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 2086273 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 2086274 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@52e5e513, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 2086702 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 2086703 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@11972007[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2086703 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@11972007[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2086706 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6f6eaef1[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2086706 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6f6eaef1[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2086707 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] 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> 2086718 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4
   [junit4]   2> 2086718 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2086739 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52e5e513
   [junit4]   2> 2086754 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52e5e513
   [junit4]   2> 2086755 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52e5e513
   [junit4]   2> 2086758 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] 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_3FEB147662B14FEE-001/tempDir-001/control/cores
   [junit4]   2> 2086758 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2086791 INFO  (coreLoadExecutor-21236-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> 2086814 INFO  (coreLoadExecutor-21236-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.0
   [junit4]   2> 2087019 WARN  (coreLoadExecutor-21236-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2087019 WARN  (coreLoadExecutor-21236-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> 2087021 INFO  (coreLoadExecutor-21236-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2087022 INFO  (coreLoadExecutor-21236-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2087022 INFO  (coreLoadExecutor-21236-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> 2087022 INFO  (coreLoadExecutor-21236-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@52e5e513
   [junit4]   2> 2087022 INFO  (coreLoadExecutor-21236-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_3FEB147662B14FEE-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_3FEB147662B14FEE-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 2087024 INFO  (coreLoadExecutor-21236-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=48, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4638735152072154]
   [junit4]   2> 2087145 INFO  (coreLoadExecutor-21236-thread-1) [    x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2087145 INFO  (coreLoadExecutor-21236-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2087146 INFO  (coreLoadExecutor-21236-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2087146 INFO  (coreLoadExecutor-21236-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2087146 INFO  (coreLoadExecutor-21236-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=23, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 2087147 INFO  (coreLoadExecutor-21236-thread-1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2087161 INFO  (coreLoadExecutor-21236-thread-1) [    x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2087422 INFO  (coreLoadExecutor-21236-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 2087422 INFO  (coreLoadExecutor-21236-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1708158357138833408
   [junit4]   2> 2087423 INFO  (searcherExecutor-21238-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2087423 INFO  (searcherExecutor-21238-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2087423 INFO  (searcherExecutor-21238-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2087423 INFO  (searcherExecutor-21238-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 2087424 INFO  (searcherExecutor-21238-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2087424 INFO  (searcherExecutor-21238-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2087424 INFO  (searcherExecutor-21238-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2087424 INFO  (searcherExecutor-21238-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2087424 INFO  (searcherExecutor-21238-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2087426 INFO  (searcherExecutor-21238-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 2088040 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] 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_3FEB147662B14FEE-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 2088041 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2088041 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 2088041 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] 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> 2088045 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2088045 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2088045 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 2088047 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7f5d07d{/rc_/n,null,AVAILABLE}
   [junit4]   2> 2088047 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.AbstractConnector Started ServerConnector@54b7879e{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:42937}
   [junit4]   2> 2088047 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.Server Started @2088075ms
   [junit4]   2> 2088047 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/rc_/n, hostPort=42937, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FEB147662B14FEE-001/tempDir-001/shard0/cores}
   [junit4]   2> 2088047 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2088047 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2088047 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 8.10.0
   [junit4]   2> 2088047 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 2088047 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
   [junit4]   2> 2088047 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2021-08-15T11:25:54.183Z
   [junit4]   2> 2088047 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] 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_3FEB147662B14FEE-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 2088050 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 2088050 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 2088051 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@52e5e513, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 2089063 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 2089064 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4b02d7a1[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2089064 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4b02d7a1[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2089067 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4c405b80[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2089067 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4c405b80[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2089069 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] 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> 2089079 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4
   [junit4]   2> 2089079 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2089099 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52e5e513
   [junit4]   2> 2089110 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52e5e513
   [junit4]   2> 2089110 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52e5e513
   [junit4]   2> 2089111 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] 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_3FEB147662B14FEE-001/tempDir-001/shard0/cores
   [junit4]   2> 2089111 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2089224 INFO  (coreLoadExecutor-21255-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> 2089241 INFO  (coreLoadExecutor-21255-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.0
   [junit4]   2> 2089241 WARN  (coreLoadExecutor-21255-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2089241 WARN  (coreLoadExecutor-21255-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> 2089243 INFO  (coreLoadExecutor-21255-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2089243 INFO  (coreLoadExecutor-21255-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2089243 INFO  (coreLoadExecutor-21255-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> 2089243 INFO  (coreLoadExecutor-21255-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@52e5e513
   [junit4]   2> 2089243 INFO  (coreLoadExecutor-21255-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_3FEB147662B14FEE-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_3FEB147662B14FEE-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 2089244 INFO  (coreLoadExecutor-21255-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=48, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4638735152072154]
   [junit4]   2> 2089456 INFO  (coreLoadExecutor-21255-thread-1) [    x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2089456 INFO  (coreLoadExecutor-21255-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2089457 INFO  (coreLoadExecutor-21255-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2089457 INFO  (coreLoadExecutor-21255-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2089458 INFO  (coreLoadExecutor-21255-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=23, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 2089459 INFO  (coreLoadExecutor-21255-thread-1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2089485 INFO  (coreLoadExecutor-21255-thread-1) [    x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2089599 INFO  (coreLoadExecutor-21255-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 2089599 INFO  (coreLoadExecutor-21255-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1708158359421583360
   [junit4]   2> 2089600 INFO  (searcherExecutor-21257-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2089601 INFO  (searcherExecutor-21257-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2089601 INFO  (searcherExecutor-21257-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2089601 INFO  (searcherExecutor-21257-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 2089601 INFO  (searcherExecutor-21257-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2089601 INFO  (searcherExecutor-21257-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2089601 INFO  (searcherExecutor-21257-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2089601 INFO  (searcherExecutor-21257-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2089601 INFO  (searcherExecutor-21257-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2089603 INFO  (searcherExecutor-21257-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 2089712 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] 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_3FEB147662B14FEE-001/tempDir-001/shard1/cores/collection1
   [junit4]   2> 2089713 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2089713 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 2089713 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] 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> 2089719 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2089719 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2089720 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 2089720 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7c99b513{/rc_/n,null,AVAILABLE}
   [junit4]   2> 2089720 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.AbstractConnector Started ServerConnector@36e21b47{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:46815}
   [junit4]   2> 2089720 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.Server Started @2089749ms
   [junit4]   2> 2089720 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/rc_/n, hostPort=46815, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FEB147662B14FEE-001/tempDir-001/shard1/cores}
   [junit4]   2> 2089720 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2089720 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2089720 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 8.10.0
   [junit4]   2> 2089720 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 2089720 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
   [junit4]   2> 2089720 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2021-08-15T11:25:55.856Z
   [junit4]   2> 2089720 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] 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_3FEB147662B14FEE-001/tempDir-001/shard1/solr.xml
   [junit4]   2> 2089723 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 2089723 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 2089724 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@52e5e513, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 2090446 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 2090460 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@8e4780e[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2090460 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@8e4780e[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2090689 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@256183b6[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2090689 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@256183b6[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2090691 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] 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> 2090702 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4
   [junit4]   2> 2090702 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2090722 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52e5e513
   [junit4]   2> 2090733 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52e5e513
   [junit4]   2> 2090734 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52e5e513
   [junit4]   2> 2090735 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] 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_3FEB147662B14FEE-001/tempDir-001/shard1/cores
   [junit4]   2> 2090735 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2090756 INFO  (coreLoadExecutor-21274-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> 2090797 INFO  (coreLoadExecutor-21274-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.0
   [junit4]   2> 2090797 WARN  (coreLoadExecutor-21274-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2090807 WARN  (coreLoadExecutor-21274-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> 2090809 INFO  (coreLoadExecutor-21274-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2090809 INFO  (coreLoadExecutor-21274-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2090809 INFO  (coreLoadExecutor-21274-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> 2090810 INFO  (coreLoadExecutor-21274-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@52e5e513
   [junit4]   2> 2090810 INFO  (coreLoadExecutor-21274-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_3FEB147662B14FEE-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_3FEB147662B14FEE-001/tempDir-001/shard1/cores/collection1/data/]
   [junit4]   2> 2090823 INFO  (coreLoadExecutor-21274-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=48, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4638735152072154]
   [junit4]   2> 2090922 INFO  (coreLoadExecutor-21274-thread-1) [    x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2090922 INFO  (coreLoadExecutor-21274-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2090923 INFO  (coreLoadExecutor-21274-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2090923 INFO  (coreLoadExecutor-21274-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2090924 INFO  (coreLoadExecutor-21274-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=23, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 2090925 INFO  (coreLoadExecutor-21274-thread-1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2090952 INFO  (coreLoadExecutor-21274-thread-1) [    x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2091222 INFO  (coreLoadExecutor-21274-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 2091222 INFO  (coreLoadExecutor-21274-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1708158361123422208
   [junit4]   2> 2091223 INFO  (searcherExecutor-21276-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2091224 INFO  (searcherExecutor-21276-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2091224 INFO  (searcherExecutor-21276-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2091224 INFO  (searcherExecutor-21276-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 2091224 INFO  (searcherExecutor-21276-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2091224 INFO  (searcherExecutor-21276-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2091224 INFO  (searcherExecutor-21276-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2091224 INFO  (searcherExecutor-21276-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2091224 INFO  (searcherExecutor-21276-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2091226 INFO  (searcherExecutor-21276-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 2091334 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] 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_3FEB147662B14FEE-001/tempDir-001/shard2/cores/collection1
   [junit4]   2> 2091335 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2091335 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 2091335 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] 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> 2091384 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2091384 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2091384 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2091385 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3777ee2{/rc_/n,null,AVAILABLE}
   [junit4]   2> 2091385 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.AbstractConnector Started ServerConnector@1eb2ccea{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:34185}
   [junit4]   2> 2091385 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.s.Server Started @2091414ms
   [junit4]   2> 2091385 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/rc_/n, hostPort=34185, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_3FEB147662B14FEE-001/tempDir-001/shard2/cores}
   [junit4]   2> 2091385 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2091385 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2091385 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 8.10.0
   [junit4]   2> 2091385 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 2091385 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
   [junit4]   2> 2091385 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2021-08-15T11:25:57.521Z
   [junit4]   2> 2091386 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] 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_3FEB147662B14FEE-001/tempDir-001/shard2/solr.xml
   [junit4]   2> 2091388 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 2091388 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 2091389 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@52e5e513, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 2091787 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 2091899 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6a44def6[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2091899 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6a44def6[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2091903 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@52ef92ba[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2091903 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@52ef92ba[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2091905 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] 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> 2091917 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4
   [junit4]   2> 2091917 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2091941 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52e5e513
   [junit4]   2> 2091953 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52e5e513
   [junit4]   2> 2091953 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52e5e513
   [junit4]   2> 2091955 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] 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_3FEB147662B14FEE-001/tempDir-001/shard2/cores
   [junit4]   2> 2091955 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[3FEB147662B14FEE]) [     ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2091983 INFO  (coreLoadExecutor-21293-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> 2092046 INFO  (coreLoadExecutor-21293-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.10.0
   [junit4]   2> 2092046 WARN  (coreLoadExecutor-21293-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2092047 WARN  (coreLoadExecutor-21293-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> 2092048 INFO  (coreLoadExecutor-21293-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2092049 INFO  (coreLoadExecutor-21293-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2092049 INFO  (coreLoadExecutor-21293-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> 2092049 INFO  (coreLoadExecutor-21293-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@52e5e513
   [junit4]   2> 2092049 INFO  (coreLoadExecutor-21293-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_3FEB147662B14FEE-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_3FEB147662B14FEE-001/tempDir-001/shard2/cores/collection1/data/]
   [junit4]   2> 2092050 INFO  (coreLoadExecutor-21293-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=48, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4638735152072154]
   [junit4]   2> 2092101 INFO  (coreLoadExecutor-21293-thread-1) [    x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2092101 INFO  (coreLoadExecutor-21293-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2092102 INFO  (coreLoadExecutor-21293-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2092102 INFO  (coreLoadExecutor-21293-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2092103 INFO  (coreLoadExecutor-21293-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=23, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 2092104 INFO  (coreLoadExecutor-21293-thread-1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2092183 INFO  (coreLoadExecutor-21293-thread-1) [    x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2092270 INFO  (coreLoadExecutor-21293-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 2092270 INFO  (coreLoadExecutor-21293-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1708158362222329856
   [junit4]   2> 2092271 INFO  (searcherExecutor-21295-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2092271 INFO  (searcherExecutor-21295-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2092271 INFO  (searcherExecutor-21295-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2092271 INFO  (searcherExecutor-21295-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 2092272 INFO  (searcherExecutor-21295-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2092272 INFO  (searcherExecutor-21295-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2092272 INFO  (searcherExecutor-21295-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2092272 INFO  (searcherExecutor-21295-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2092272 INFO  (searcherExecutor-21295-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2092274 INFO  (searcherExecutor-21295-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.

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

letContextHandler@3777ee2{/rc_/n,null,STOPPED}
   [junit4]   2> 2093236 INFO  (closeThreadPool-21303-thread-7) [     ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7c99b513{/rc_/n,null,STOPPED}
   [junit4]   2> 2093236 INFO  (closeThreadPool-21303-thread-4) [     ] o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 2093236 INFO  (closeThreadPool-21303-thread-7) [     ] o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 2093236 INFO  (closeThreadPool-21303-thread-2) [     ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6ad8a39f{/rc_/n,null,STOPPED}
   [junit4]   2> 2093236 INFO  (closeThreadPool-21303-thread-2) [     ] o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=DistributedQueryComponentCustomSortTest -Dtests.method=test -Dtests.seed=3FEB147662B14FEE -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=pl -Dtests.timezone=America/Yakutat -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] FAILURE 7.46s J3 | DistributedQueryComponentCustomSortTest.test <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Number of documents (1) is different from number of expected values (3
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([3FEB147662B14FEE:B7BF2BACCC4D2216]:0)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertFieldValues(SolrTestCaseJ4.java:2184)
   [junit4]    > 	at org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.test(DistributedQueryComponentCustomSortTest.java:128)
   [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> 2093242 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 2093242 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1149493680
   [junit4]   2> 2093242 INFO  (coreCloseExecutor-21321-thread-1) [     ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@c625b32
   [junit4]   2> 2093242 INFO  (coreCloseExecutor-21321-thread-1) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@c625b32
   [junit4]   2> 2093242 INFO  (coreCloseExecutor-21321-thread-1) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2bee5b5c: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@7431ae35
   [junit4]   2> 2093245 INFO  (coreCloseExecutor-21321-thread-1) [     ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  ... SKIPPED (unnecessary).
   [junit4]   2> 2093249 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
   [junit4]   2> 2093249 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@110ab7f6: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@1198e98c
   [junit4]   2> 2093250 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
   [junit4]   2> 2093250 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@541f850c: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@607ea287
   [junit4]   2> 2093250 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
   [junit4]   2> 2093250 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[3FEB147662B14FEE]-worker) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@22abd5ae: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@5ddf4b89
   [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_3FEB147662B14FEE-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene87): {_root_=PostingsFormat(name=LuceneFixedGap), id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), text=PostingsFormat(name=MockRandom), asdfasdf_field_should_match_catchall_dynamic_field_adsfasdf=PostingsFormat(name=LuceneFixedGap)}, docValues:{payload=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1363, maxMBSortInHeap=7.187217837516784, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=pl, timezone=America/Yakutat
   [junit4]   2> NOTE: Linux 4.15.0-147-generic amd64/Oracle Corporation 1.8.0_291 (64-bit)/cpus=4,threads=1,free=246787696,total=525336576
   [junit4]   2> NOTE: All tests run in this JVM: [TestMergePolicyConfig, TestConfigSetsAPIExclusivity, TestSolrConfigHandler, TestGroupingSearch, AlternateDirectoryTest, SimplePostToolTest, SystemLogListenerTest, PeerSyncTest, BlockCacheTest, TestSizeLimitedDistributedMap, QueryResultKeyTest, IndexSizeTriggerMixedBoundsTest, HdfsBackupRepositoryIntegrationTest, TestRuleBasedAuthorizationWithKerberos, AddReplicaTest, TestDistributedGrouping, LegacyCloudClusterPropTest, TestExplainDocTransformer, TestCopyFieldCollectionResource, TestManagedResource, ForceLeaderWithTlogReplicasTest, TestPayloadCheckQParserPlugin, TestJsonRequest, SOLR749Test, TestRTimerTree, TestPKIAuthenticationPlugin, TestHashQParserPlugin, SolrIndexConfigTest, TestReload, UtilsToolTest, JSONWriterTest, TestFieldCacheReopen, TestFunctionQuery, TestSimpleQParserPlugin, RequiredFieldsTest, DataDrivenBlockJoinTest, DocValuesMissingTest, TestSurroundQueryParser, TestFieldCacheWithThreads, CachingDirectoryFactoryTest, CopyFieldTest, TestSnapshotCoreBackup, ChaosMonkeySafeLeaderTest, TestUniqueKeyFieldResource, TestHalfAndHalfDocValues, HdfsRecoveryZkTest, HdfsChaosMonkeyNothingIsSafeTest, TestUnInvertedFieldException, RankQParserPluginTest, MetricsHandlerTest, FieldAnalysisRequestHandlerTest, PreAnalyzedFieldManagedSchemaCloudTest, MinimalSchemaTest, CloudReplicaSourceTest, TlogReplayBufferedWhileIndexingTest, CurrencyRangeFacetCloudTest, JvmMetricsTest, TestInPlaceUpdatesRequiredField, CoreAdminCreateDiscoverTest, TestSolrCloudWithSecureImpersonation, TestInfoStreamLogging, SpellCheckComponentTest, FileUtilsTest, DirectSolrConnectionTest, ImplicitSnitchTest, TestOmitPositions, SecurityHeadersTest, TestDeleteCollectionOnDownNodes, TestFilteredDocIdSet, TestCustomDocTransformer, XmlInterpolationTest, TestCollectionsAPIViaSolrCloudCluster, TestReplicationHandler, BitVectorTest, ResponseBuilderTest, ExternalFileFieldSortTest, TestPrepRecovery, TestExportWriter, LeaderFailoverAfterPartitionTest, TestNonDefinedSimilarityFactory, TestRestoreCore, CoreMergeIndexesAdminHandlerTest, SegmentsInfoRequestHandlerTest, SolrXmlInZkTest, HdfsTlogReplayBufferedWhileIndexingTest, TestUpdate, TestXmlQParser, TestBooleanSimilarityFactory, ConnectionManagerTest, DistributedMLTComponentTest, BasicAuthIntegrationTest, TestAnalyzeInfixSuggestions, UpdateRequestProcessorFactoryTest, DistributedFacetPivotLongTailTest, SharedFSAutoReplicaFailoverTest, TestLeaderElectionZkExpiry, TestSolrCachePerf, CategoryRoutedAliasUpdateProcessorTest, TestFreeTextSuggestions, MissingSegmentRecoveryTest, TestSnapshotCloudManager, TestJsonFacetsWithNestedObjects, TestSystemIdResolver, ShardSplitTest, BasicDistributedZkTest, TestSchemaResource, DistributedSpellCheckComponentTest, RandomizedTaggerTest, SolrTestCaseJ4DeleteCoreTest, TestUtilizeNode, TestConfigOverlay, TestComplexPhraseQParserPlugin, TestRequestStatusCollectionAPI, SolrIndexSearcherTest, TestLRUCache, ManagedSchemaDiffTest, BlockJoinFacetSimpleTest, DeleteInactiveReplicaTest, DeleteShardTest, DistribDocExpirationUpdateProcessorTest, LeaderElectionContextKeyTest, MetricsHistoryIntegrationTest, MultiSolrCloudTestCaseTest, MultiThreadedOCPTest, PackageManagerCLITest, RecoveryZkTest, ReindexCollectionTest, TestCloudInspectUtil, TestCloudPhrasesIdentificationComponent, TestCloudPseudoReturnFields, TestCloudRecovery2, TestDistributedMap, TestDownShardTolerantSearch, TestPullReplicaErrorHandling, TestQueryingOnDownCollection, TestRebalanceLeaders, TestStressLiveNodes, ComputePlanActionTest, NodeMarkersRegistrationTest, ScheduledMaintenanceTriggerTest, ScheduledTriggerIntegrationTest, TestSimComputePlanAction, TestSimDistribStateManager, TestSimLargeCluster, CdcrWithNodesRestartsTest, HDFSCollectionsAPITest, ExitableDirectoryReaderTest, TestBackupRepositoryFactory, TestBadConfig, TestCorePropertiesReload, TestCustomStream, TestSolrXml, TestSolrCloudSnapshots, TestSQLHandler, XmlUpdateRequestHandlerTest, HealthCheckHandlerTest, TestApiFramework, TestCollectionAPIs, TestConfigsApi, ThreadDumpHandlerTest, ZookeeperReadAPITest, DebugComponentTest, DistributedDebugComponentTest, DistributedQueryComponentCustomSortTest]
   [junit4] Completed [708/945 (1!)] on J3 in 8.73s, 1 test, 1 failure <<< FAILURES!

[...truncated 53318 lines...]