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/11/11 17:15:16 UTC

[JENKINS] Lucene » Lucene-Solr-Tests-8.11 - Build # 59 - Unstable!

Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.11/59/

1 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.handler.admin.MetricsHistoryHandlerTest

Error Message:
autoscaling config is empty

Stack Trace:
java.lang.AssertionError: autoscaling config is empty
	at __randomizedtesting.SeedInfo.seed([7BFD72B259BE51C8]:0)
	at org.junit.Assert.fail(Assert.java:89)
	at org.junit.Assert.assertTrue(Assert.java:42)
	at org.junit.Assert.assertFalse(Assert.java:65)
	at org.apache.solr.handler.admin.MetricsHistoryHandlerTest.beforeClass(MetricsHistoryHandlerTest.java:72)
	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$6.evaluate(RandomizedRunner.java:878)
	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 13348 lines...]
   [junit4] Suite: org.apache.solr.handler.admin.MetricsHistoryHandlerTest
   [junit4]   2> 2091 INFO  (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-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.11/solr/server/solr/configsets/_default/conf'
   [junit4]   2> 2110 INFO  (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-worker) [     ] o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-1 for ERROR logs matching regex: ignore_exception
   [junit4]   2> 2115 INFO  (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-worker) [     ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.admin.MetricsHistoryHandlerTest_7BFD72B259BE51C8-001/data-dir-1-001
   [junit4]   2> 2275 INFO  (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-worker) [     ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 2328 INFO  (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-worker) [     ] o.e.j.u.log Logging initialized @2350ms to org.eclipse.jetty.util.log.Slf4jLog
   [junit4]   2> 2334 INFO  (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-worker) [     ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 2622 INFO  (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-worker) [     ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 2623 INFO  (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-worker) [     ] o.a.s.c.SolrCloudTestCase Using per-replica state
   [junit4]   2> 2699 INFO  (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-worker) [     ] o.a.s.c.SolrPaths solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 2821 DEBUG (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-worker) [     ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10000_solr
   [junit4]   2> 2838 DEBUG (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-worker) [     ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=0
   [junit4]   2> 2843 DEBUG (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-worker) [     ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
   [junit4]   2> 2843 INFO  (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-worker) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2856 DEBUG (Simulated OverseerAutoScalingTriggerThread) [     ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
   [junit4]   2> 3061 DEBUG (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-worker) [     ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
   [junit4]   2> 3062 DEBUG (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-worker) [     ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
   [junit4]   2> 3062 DEBUG (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-worker) [     ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
   [junit4]   2> 3062 DEBUG (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-worker) [     ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
   [junit4]   2> 3062 DEBUG (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-worker) [     ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
   [junit4]   2> 3062 DEBUG (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-worker) [     ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
   [junit4]   2> 3062 WARN  (Simulated OverseerAutoScalingTriggerThread) [     ] o.a.s.c.a.OverseerTriggerThread Interrupted
   [junit4]   2>           => java.lang.InterruptedException
   [junit4]   2> 	at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1220)
   [junit4]   2> java.lang.InterruptedException: null
   [junit4]   2> 	at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1220) ~[?:1.8.0_291]
   [junit4]   2> 	at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:335) ~[?:1.8.0_291]
   [junit4]   2> 	at org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:197) [java/:?]
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_291]
   [junit4]   2> 3095 INFO  (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-worker) [     ] o.a.s.u.ErrorLogMuter Closing ErrorLogMuter-regex-1 after mutting 0 log messages
   [junit4]   2> 3095 INFO  (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-worker) [     ] o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-2 for ERROR logs matching regex: ignore_exception
   [junit4]   2> 3096 INFO  (SUITE-MetricsHistoryHandlerTest-seed#[7BFD72B259BE51C8]-worker) [     ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene87): {}, docValues:{}, maxPointsInLeafNode=341, maxMBSortInHeap=7.830845792456127, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=de-LU, timezone=SystemV/MST7MDT
   [junit4]   2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_291 (64-bit)/cpus=4,threads=1,free=205304504,total=267911168
   [junit4]   2> NOTE: All tests run in this JVM: [MetricsHistoryHandlerTest]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=MetricsHistoryHandlerTest -Dtests.seed=7BFD72B259BE51C8 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=de-LU -Dtests.timezone=SystemV/MST7MDT -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.00s J1 | MetricsHistoryHandlerTest (suite) <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: autoscaling config is empty
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([7BFD72B259BE51C8]:0)
   [junit4]    > 	at org.apache.solr.handler.admin.MetricsHistoryHandlerTest.beforeClass(MetricsHistoryHandlerTest.java:72)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:748)
   [junit4] Completed [2/952 (1!)] on J1 in 2.62s, 0 tests, 1 failure <<< FAILURES!

[...truncated 57508 lines...]

[JENKINS] Lucene » Lucene-Solr-Tests-8.11 - Build # 60 - Still Unstable!

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

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

Error Message:
Error from server at https://127.0.0.1:37175/collection1: org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded:[https://[::1]:8, https://[::1]:6]

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:37175/collection1: org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded:[https://[::1]:8, https://[::1]:6]
	at __randomizedtesting.SeedInfo.seed([F2437FA54765BDF7:7A17407FE999D00F]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:681)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
	at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:214)
	at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1003)
	at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1018)
	at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:623)
	at org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.queryAllowPartialResults(DistributedQueryComponentCustomSortTest.java:139)
	at org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.test(DistributedQueryComponentCustomSortTest.java:129)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1084)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1055)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.junit.rules.RunRules.evaluate(RunRules.java:20)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.junit.rules.RunRules.evaluate(RunRules.java:20)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.junit.rules.RunRules.evaluate(RunRules.java:20)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at org.junit.rules.RunRules.evaluate(RunRules.java:20)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 15782 lines...]
   [junit4] Suite: org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest
   [junit4]   2> 2062398 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-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.11/solr/server/solr/configsets/_default/conf'
   [junit4]   2> 2062399 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.u.ErrorLogMuter Closing ErrorLogMuter-regex-766 after mutting 0 log messages
   [junit4]   2> 2062399 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-767 for ERROR logs matching regex: ignore_exception
   [junit4]   2> 2062400 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/data-dir-163-001
   [junit4]   2> 2062400 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=22 numCloses=22
   [junit4]   2> 2062401 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 2062402 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 2062403 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 2062403 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 2062403 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 2062409 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 2062426 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
   [junit4]   2> 2062426 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2062426 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
   [junit4]   2> 2062427 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2062428 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2063933 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.c.CoreContainer Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/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.11/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.11/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:830) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:820) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:667) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:656) [java/:?]
   [junit4]   2> 	at org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.setUpBeforeClass(DistributedQueryComponentCustomSortTest.java:44) [test/:?]
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_291]
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_291]
   [junit4]   2> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_291]
   [junit4]   2> 	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_291]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:878) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.1.jar:4.13.1]
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) [java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) [java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) [java/:?]
   [junit4]   2> 	at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.1.jar:4.13.1]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_291]
   [junit4]   2> 2063934 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.f.DistribPackageStore Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/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.11/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.11/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:683) [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:830) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:820) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:667) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:656) [java/:?]
   [junit4]   2> 	at org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.setUpBeforeClass(DistributedQueryComponentCustomSortTest.java:44) [test/:?]
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_291]
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_291]
   [junit4]   2> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_291]
   [junit4]   2> 	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_291]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:878) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.1.jar:4.13.1]
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) [java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) [java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) [java/:?]
   [junit4]   2> 	at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.1.jar:4.13.1]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) [randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_291]
   [junit4]   2> 2063935 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 2063936 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 2063937 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@241ecaf2[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2063937 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@241ecaf2[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2063939 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 2063940 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@195327a2[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2063940 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@195327a2[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2063941 WARN  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-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> 2063971 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63ae9675
   [junit4]   2> 2063996 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63ae9675
   [junit4]   2> 2063996 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63ae9675
   [junit4]   2> 2063997 INFO  (coreLoadExecutor-20571-thread-1) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024
   [junit4]   2> 2064005 INFO  (coreLoadExecutor-20571-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.11/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 2064024 INFO  (coreLoadExecutor-20571-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
   [junit4]   2> 2064024 WARN  (coreLoadExecutor-20571-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2064024 WARN  (coreLoadExecutor-20571-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> 2064025 INFO  (coreLoadExecutor-20571-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2064026 INFO  (coreLoadExecutor-20571-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2064026 INFO  (coreLoadExecutor-20571-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.11/solr/core/src/test-files/solr/collection1, trusted=true
   [junit4]   2> 2064026 INFO  (coreLoadExecutor-20571-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@63ae9675
   [junit4]   2> 2064026 INFO  (coreLoadExecutor-20571-thread-1) [    x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/data-dir-163-001/]
   [junit4]   2> 2064028 INFO  (coreLoadExecutor-20571-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=48, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 2064081 INFO  (coreLoadExecutor-20571-thread-1) [    x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2064081 INFO  (coreLoadExecutor-20571-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2064082 INFO  (coreLoadExecutor-20571-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2064082 INFO  (coreLoadExecutor-20571-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2064082 INFO  (coreLoadExecutor-20571-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=93.798828125, floorSegmentMB=1.400390625, forceMergeDeletesPctAllowed=0.2349057239300334, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=31.667854215864097
   [junit4]   2> 2064083 INFO  (coreLoadExecutor-20571-thread-1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2064099 INFO  (coreLoadExecutor-20571-thread-1) [    x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2064161 INFO  (coreLoadExecutor-20571-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 2064161 INFO  (coreLoadExecutor-20571-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1716164607435866112
   [junit4]   2> 2064162 INFO  (searcherExecutor-20573-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2064162 INFO  (searcherExecutor-20573-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2064163 INFO  (searcherExecutor-20573-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2064163 INFO  (searcherExecutor-20573-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 2064163 INFO  (searcherExecutor-20573-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2064163 INFO  (searcherExecutor-20573-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2064163 INFO  (searcherExecutor-20573-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2064163 INFO  (searcherExecutor-20573-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2064163 INFO  (searcherExecutor-20573-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2064164 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 2064165 INFO  (searcherExecutor-20573-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 2064170 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.u.ErrorLogMuter Closing ErrorLogMuter-regex-767 after mutting 0 log messages
   [junit4]   2> 2064170 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-768 for ERROR logs matching regex: ignore_exception
   [junit4]   2> 2064286 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/control/cores/collection1
   [junit4]   2> 2064287 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2064288 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 2064288 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10
   [junit4]   2> 2064292 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2064292 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2064292 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2064292 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@197ea99{/,null,AVAILABLE}
   [junit4]   2> 2064293 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.AbstractConnector Started ServerConnector@1a7e2335{SSL, (ssl, http/1.1)}{127.0.0.1:38151}
   [junit4]   2> 2064293 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.Server Started @2064336ms
   [junit4]   2> 2064293 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/, hostPort=38151, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/control/cores}
   [junit4]   2> 2064293 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2064293 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2064293 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 8.11.0
   [junit4]   2> 2064293 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 2064293 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr
   [junit4]   2> 2064293 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2021-11-11T20:21:48.969Z
   [junit4]   2> 2064293 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/control/solr.xml
   [junit4]   2> 2064296 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 2064296 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 2064297 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@63ae9675, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 2064708 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 2064708 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 2064709 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@22a732aa[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2064709 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@22a732aa[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2064712 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 2064713 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@50752559[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2064713 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@50752559[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2064714 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] 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> 2064727 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4
   [junit4]   2> 2064727 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2064749 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63ae9675
   [junit4]   2> 2064759 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63ae9675
   [junit4]   2> 2064759 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63ae9675
   [junit4]   2> 2064761 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/control/cores
   [junit4]   2> 2064761 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2064770 INFO  (coreLoadExecutor-20591-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.11/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 2064801 INFO  (coreLoadExecutor-20591-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
   [junit4]   2> 2064801 WARN  (coreLoadExecutor-20591-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2064801 WARN  (coreLoadExecutor-20591-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> 2064803 INFO  (coreLoadExecutor-20591-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2064803 INFO  (coreLoadExecutor-20591-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2064803 INFO  (coreLoadExecutor-20591-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.11/solr/core/src/test-files/solr/collection1, trusted=true
   [junit4]   2> 2064803 INFO  (coreLoadExecutor-20591-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@63ae9675
   [junit4]   2> 2064804 INFO  (coreLoadExecutor-20591-thread-1) [    x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/control/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 2064805 INFO  (coreLoadExecutor-20591-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=48, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 2064865 INFO  (coreLoadExecutor-20591-thread-1) [    x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2064865 INFO  (coreLoadExecutor-20591-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2064866 INFO  (coreLoadExecutor-20591-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2064866 INFO  (coreLoadExecutor-20591-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2064867 INFO  (coreLoadExecutor-20591-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=93.798828125, floorSegmentMB=1.400390625, forceMergeDeletesPctAllowed=0.2349057239300334, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=31.667854215864097
   [junit4]   2> 2064868 INFO  (coreLoadExecutor-20591-thread-1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2064882 INFO  (coreLoadExecutor-20591-thread-1) [    x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2064952 INFO  (coreLoadExecutor-20591-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 2064952 INFO  (coreLoadExecutor-20591-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1716164608265289728
   [junit4]   2> 2064953 INFO  (searcherExecutor-20593-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2064953 INFO  (searcherExecutor-20593-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2064953 INFO  (searcherExecutor-20593-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2064953 INFO  (searcherExecutor-20593-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 2064954 INFO  (searcherExecutor-20593-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2064954 INFO  (searcherExecutor-20593-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2064954 INFO  (searcherExecutor-20593-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2064954 INFO  (searcherExecutor-20593-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2064954 INFO  (searcherExecutor-20593-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2064956 INFO  (searcherExecutor-20593-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 2065573 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 2065574 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2065574 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 2065574 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10
   [junit4]   2> 2065587 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2065587 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2065587 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 2065587 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3047bb8d{/,null,AVAILABLE}
   [junit4]   2> 2065588 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.AbstractConnector Started ServerConnector@3586bf4f{SSL, (ssl, http/1.1)}{127.0.0.1:37175}
   [junit4]   2> 2065588 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.Server Started @2065631ms
   [junit4]   2> 2065588 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/, hostPort=37175, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/shard0/cores}
   [junit4]   2> 2065588 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2065588 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2065588 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 8.11.0
   [junit4]   2> 2065588 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 2065588 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr
   [junit4]   2> 2065588 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2021-11-11T20:21:50.264Z
   [junit4]   2> 2065588 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 2065591 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 2065591 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 2065592 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@63ae9675, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 2066202 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 2066202 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 2066203 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5694288f[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2066203 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5694288f[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2066210 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 2066211 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@288c4c44[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2066211 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@288c4c44[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2066213 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] 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> 2066224 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4
   [junit4]   2> 2066224 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2066246 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63ae9675
   [junit4]   2> 2066258 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63ae9675
   [junit4]   2> 2066259 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63ae9675
   [junit4]   2> 2066260 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/shard0/cores
   [junit4]   2> 2066260 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2066270 INFO  (coreLoadExecutor-20610-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.11/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 2066303 INFO  (coreLoadExecutor-20610-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
   [junit4]   2> 2066304 WARN  (coreLoadExecutor-20610-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2066304 WARN  (coreLoadExecutor-20610-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> 2066305 INFO  (coreLoadExecutor-20610-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2066306 INFO  (coreLoadExecutor-20610-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2066306 INFO  (coreLoadExecutor-20610-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.11/solr/core/src/test-files/solr/collection1, trusted=true
   [junit4]   2> 2066306 INFO  (coreLoadExecutor-20610-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@63ae9675
   [junit4]   2> 2066306 INFO  (coreLoadExecutor-20610-thread-1) [    x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/shard0/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 2066309 INFO  (coreLoadExecutor-20610-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=48, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 2066366 INFO  (coreLoadExecutor-20610-thread-1) [    x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2066366 INFO  (coreLoadExecutor-20610-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2066368 INFO  (coreLoadExecutor-20610-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2066368 INFO  (coreLoadExecutor-20610-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2066369 INFO  (coreLoadExecutor-20610-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=93.798828125, floorSegmentMB=1.400390625, forceMergeDeletesPctAllowed=0.2349057239300334, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=31.667854215864097
   [junit4]   2> 2066370 INFO  (coreLoadExecutor-20610-thread-1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2066385 INFO  (coreLoadExecutor-20610-thread-1) [    x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2066451 INFO  (coreLoadExecutor-20610-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 2066452 INFO  (coreLoadExecutor-20610-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1716164609838153728
   [junit4]   2> 2066453 INFO  (searcherExecutor-20612-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2066453 INFO  (searcherExecutor-20612-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2066453 INFO  (searcherExecutor-20612-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2066453 INFO  (searcherExecutor-20612-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 2066454 INFO  (searcherExecutor-20612-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2066454 INFO  (searcherExecutor-20612-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2066454 INFO  (searcherExecutor-20612-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2066454 INFO  (searcherExecutor-20612-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2066454 INFO  (searcherExecutor-20612-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2066456 INFO  (searcherExecutor-20612-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 2067066 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/shard1/cores/collection1
   [junit4]   2> 2067067 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2067067 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 2067067 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10
   [junit4]   2> 2067069 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2067070 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2067070 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2067070 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@11c03e94{/,null,AVAILABLE}
   [junit4]   2> 2067071 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.AbstractConnector Started ServerConnector@4d909229{SSL, (ssl, http/1.1)}{127.0.0.1:34861}
   [junit4]   2> 2067071 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.Server Started @2067114ms
   [junit4]   2> 2067071 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/, hostPort=34861, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/shard1/cores}
   [junit4]   2> 2067071 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2067071 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2067071 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 8.11.0
   [junit4]   2> 2067071 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 2067071 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr
   [junit4]   2> 2067071 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2021-11-11T20:21:51.747Z
   [junit4]   2> 2067071 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/shard1/solr.xml
   [junit4]   2> 2067073 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 2067074 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 2067075 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@63ae9675, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 2067615 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 2067615 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 2067616 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@64151b23[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2067616 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@64151b23[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2067619 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 2067621 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@235bfa05[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2067621 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@235bfa05[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2067623 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] 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> 2067635 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4
   [junit4]   2> 2067635 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2067657 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63ae9675
   [junit4]   2> 2067684 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63ae9675
   [junit4]   2> 2067684 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63ae9675
   [junit4]   2> 2067686 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/shard1/cores
   [junit4]   2> 2067686 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2067694 INFO  (coreLoadExecutor-20629-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.11/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 2067713 INFO  (coreLoadExecutor-20629-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
   [junit4]   2> 2067713 WARN  (coreLoadExecutor-20629-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2067714 WARN  (coreLoadExecutor-20629-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> 2067715 INFO  (coreLoadExecutor-20629-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2067715 INFO  (coreLoadExecutor-20629-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2067715 INFO  (coreLoadExecutor-20629-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.11/solr/core/src/test-files/solr/collection1, trusted=true
   [junit4]   2> 2067716 INFO  (coreLoadExecutor-20629-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@63ae9675
   [junit4]   2> 2067716 INFO  (coreLoadExecutor-20629-thread-1) [    x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/shard1/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/shard1/cores/collection1/data/]
   [junit4]   2> 2067718 INFO  (coreLoadExecutor-20629-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=48, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 2067775 INFO  (coreLoadExecutor-20629-thread-1) [    x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2067775 INFO  (coreLoadExecutor-20629-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2067776 INFO  (coreLoadExecutor-20629-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2067776 INFO  (coreLoadExecutor-20629-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2067777 INFO  (coreLoadExecutor-20629-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=93.798828125, floorSegmentMB=1.400390625, forceMergeDeletesPctAllowed=0.2349057239300334, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=31.667854215864097
   [junit4]   2> 2067778 INFO  (coreLoadExecutor-20629-thread-1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2067791 INFO  (coreLoadExecutor-20629-thread-1) [    x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2067857 INFO  (coreLoadExecutor-20629-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 2067857 INFO  (coreLoadExecutor-20629-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1716164611311403008
   [junit4]   2> 2067859 INFO  (searcherExecutor-20631-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2067859 INFO  (searcherExecutor-20631-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2067859 INFO  (searcherExecutor-20631-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2067859 INFO  (searcherExecutor-20631-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 2067859 INFO  (searcherExecutor-20631-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2067859 INFO  (searcherExecutor-20631-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2067860 INFO  (searcherExecutor-20631-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2067860 INFO  (searcherExecutor-20631-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2067860 INFO  (searcherExecutor-20631-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2067862 INFO  (searcherExecutor-20631-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 2067976 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/shard2/cores/collection1
   [junit4]   2> 2067977 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2067977 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 2067977 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10
   [junit4]   2> 2067984 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2067984 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2067984 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2067984 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@42771578{/,null,AVAILABLE}
   [junit4]   2> 2067985 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.AbstractConnector Started ServerConnector@3d994e2b{SSL, (ssl, http/1.1)}{127.0.0.1:40363}
   [junit4]   2> 2067985 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.s.Server Started @2068028ms
   [junit4]   2> 2067985 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-custom-field.xml, solrconfig=solrconfig.xml, hostContext=/, hostPort=40363, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/shard2/cores}
   [junit4]   2> 2067985 ERROR (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2067985 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2067985 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 8.11.0
   [junit4]   2> 2067985 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 2067985 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr
   [junit4]   2> 2067985 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2021-11-11T20:21:52.661Z
   [junit4]   2> 2067986 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/shard2/solr.xml
   [junit4]   2> 2067988 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 2067988 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 2067989 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@63ae9675, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 2068454 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 2068454 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 2068455 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@21f9b378[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2068455 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@21f9b378[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2068457 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 2068458 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1dc38165[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2068458 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1dc38165[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2068460 WARN  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] 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> 2068473 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4
   [junit4]   2> 2068473 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2068509 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63ae9675
   [junit4]   2> 2068522 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63ae9675
   [junit4]   2> 2068522 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63ae9675
   [junit4]   2> 2068524 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001/tempDir-001/shard2/cores
   [junit4]   2> 2068524 INFO  (TEST-DistributedQueryComponentCustomSortTest.test-seed#[F2437FA54765BDF7]) [     ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2068532 INFO  (coreLoadExecutor-20648-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.11/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 2068555 INFO  (coreLoadExecutor-20648-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
   [junit4]   2> 2068556 WARN  (coreLoadExecutor-20648-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
   [junit4]   2> 2068556 WARN  (coreLoadExecutor-20648-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> 2068558 INFO  (coreLoadExecutor-20648-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Schema name=test-custom-field-sort
   [junit4]   2> 2068558 INFO  (coreLoadExecutor-20648-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-custom-field-sort/1.6 with uniqueid field id
   [junit4]   2> 2068558 INFO  (coreLoadExecutor-20648-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.11/solr/core/src/test-files/solr/collection1, trusted=true
   [junit4]   2> 2068558 INFO  (coreLoadExecutor-20648-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@63ae9675
   [junit4]   2> 2068559 INFO  (coreLoadExecutor-20648-thread-1) [    x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryCompon

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

SolrClient.executeMethod(HttpSolrClient.java:681)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
   [junit4]    > 	at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:214)
   [junit4]    > 	at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1003)
   [junit4]    > 	at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1018)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:623)
   [junit4]    > 	at org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.queryAllowPartialResults(DistributedQueryComponentCustomSortTest.java:139)
   [junit4]    > 	at org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.test(DistributedQueryComponentCustomSortTest.java:129)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1084)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1055)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 2069634 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 2069634 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1432564631
   [junit4]   2> 2069634 INFO  (coreCloseExecutor-20676-thread-1) [     ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@6dbe68a4
   [junit4]   2> 2069634 INFO  (coreCloseExecutor-20676-thread-1) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@6dbe68a4
   [junit4]   2> 2069634 INFO  (coreCloseExecutor-20676-thread-1) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@17485477: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@5c74a3c5
   [junit4]   2> 2069638 INFO  (coreCloseExecutor-20676-thread-1) [     ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  ... SKIPPED (unnecessary).
   [junit4]   2> 2069638 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
   [junit4]   2> 2069639 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@d0c9fa4: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@7710b1c3
   [junit4]   2> 2069640 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
   [junit4]   2> 2069640 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@690b5a9f: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@2bc36f01
   [junit4]   2> 2069640 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
   [junit4]   2> 2069640 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@24586490: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@7afc7194
   [junit4]   2> 2069642 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.u.ErrorLogMuter Closing ErrorLogMuter-regex-768 after mutting 0 log messages
   [junit4]   2> 2069642 INFO  (SUITE-DistributedQueryComponentCustomSortTest-seed#[F2437FA54765BDF7]-worker) [     ] o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-769 for ERROR logs matching regex: ignore_exception
   [junit4]   2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryComponentCustomSortTest_F2437FA54765BDF7-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene87): {_root_=PostingsFormat(name=Direct), id=PostingsFormat(name=MockRandom), text=PostingsFormat(name=LuceneVarGapFixedInterval), asdfasdf_field_should_match_catchall_dynamic_field_adsfasdf=PostingsFormat(name=Direct)}, docValues:{payload=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1668, maxMBSortInHeap=6.311564594921453, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=pt-BR, timezone=Africa/Monrovia
   [junit4]   2> NOTE: Linux 4.15.0-147-generic amd64/Oracle Corporation 1.8.0_291 (64-bit)/cpus=4,threads=1,free=225710048,total=523239424
   [junit4]   2> NOTE: All tests run in this JVM: [ShardRoutingTest, TestSolrJ, TestCloudPseudoReturnFields, UpdateLogCloudTest, CdcrRequestHandlerTest, TestCSVResponseWriter, TestStreamBody, TestSchemaNameResource, TestRawTransformer, ChaosMonkeyNothingIsSafeTest, JWTVerificationkeyResolverTest, TestSolr4Spatial2, IndexBasedSpellCheckerTest, TestDocSet, TestBulkSchemaAPI, TestObjectReleaseTracker, HighlighterMaxOffsetTest, SyncSliceTest, TestDynamicURP, PeerSyncTest, MetricsHistoryHandlerTest, TestSolrCloudWithDelegationTokens, BlockJoinFacetRandomTest, TestPerFieldSimilarityWithDefaultOverride, RoutingToNodesWithPropertiesTest, TestSimTriggerIntegration, TestSimNodeAddedTrigger, TestDocBasedVersionConstraints, SpellCheckComponentTest, ManagedSchemaRoundRobinCloudTest, BasicAuthStandaloneTest, SpellingQueryConverterTest, LeaderTragicEventTest, QueryParsingTest, DistribDocExpirationUpdateProcessorTest, DocExpirationUpdateProcessorFactoryTest, SolrCoreTest, TestTolerantUpdateProcessorCloud, ChangedSchemaMergeTest, LocalFSCloudIncrementalBackupTest, HttpPartitionOnCommitTest, TestManagedSchemaThreadSafety, LargeFieldTest, ConnectionReuseTest, CreateRoutedAliasTest, TestOverriddenPrefixQueryForCustomFieldType, TestMacros, SolrRequestParserTest, TestAnalyzedSuggestions, SearchRateTriggerIntegrationTest, IndexSizeTriggerTest, TestScoreJoinQPScore, TestLegacyFieldCache, TestDistribIDF, BlockDirectoryTest, MoveReplicaHDFSTest, BinaryUpdateRequestHandlerTest, TestPackages, TestSortByMinMaxFunction, TestWithCollection, SolrIndexConfigTest, TestHashPartitioner, TimeZoneUtilsTest, TestCircuitBreaker, RestoreTriggerStateTest, TestAddFieldRealTimeGet, TestLocalFSCloudBackupRestore, TestValueSourceCache, HdfsSyncSliceTest, TestSubQueryTransformerDistrib, DistributedFacetPivotSmallAdvancedTest, TestMiniSolrCloudClusterSSL, TestContainerPlugin, HttpSolrCallGetCoreTest, ResponseBuilderTest, TestPullReplicaErrorHandling, CdcrBidirectionalTest, TestSolrJacksonAnnotation, TestRandomRequestDistribution, DistributedSuggestComponentTest, TestCloudDeleteByQuery, PeerSyncWithLeaderTest, HdfsNNFailoverTest, SimpleMLTQParserTest, ProtectedTermFilterFactoryTest, TestSQLHandler, ConcurrentDeleteAndCreateCollectionTest, TestComplexPhraseLeadingWildcard, AssignTest, TestFieldCacheSort, TestMinHashQParser, TestQueryTypes, SortByFunctionTest, TolerantUpdateProcessorTest, TestFacetMethods, ClusterStateTest, TestSystemCollAutoCreate, PrimUtilsTest, TestConfLoadPerf, DaemonStreamApiTest, TestEmbeddedSolrServerAdminHandler, VersionInfoTest, TestShardHandlerFactory, TestSolrDeletionPolicy2, NestedShardedAtomicUpdateTest, DistributedExpandComponentTest, RulesTest, TestSegmentSorting, TestInPlaceUpdatesRequiredField, ReplicationFactorTest, TestSchemaDesignerSettingsDAO, TestJsonFacetsStatsParsing, BigEndianAscendingWordSerializerTest, AddBlockUpdateTest, TestReplicationHandlerBackup, AssignBackwardCompatibilityTest, CollectionStateFormat2Test, ConcurrentCreateRoutedAliasTest, ConnectionManagerTest, DeleteNodeTest, DeleteReplicaTest, LeaderElectionTest, MetricsHistoryIntegrationTest, MetricsHistoryWithAuthIntegrationTest, MigrateRouteKeyTest, OverseerModifyCollectionTest, OverseerTaskQueueTest, PeerSyncReplicationTest, SolrXmlInZkTest, TestClusterProperties, TestConfigSetsAPI, TestConfigSetsAPIExclusivity, TestConfigSetsAPIShareSchema, TestConfigSetsAPIZkFailure, TestDeleteCollectionOnDownNodes, TestDistribDocBasedVersion, TestStressInPlaceUpdates, VMParamsZkACLAndCredentialsProvidersTest, ZkCLITest, CollectionReloadTest, CollectionTooManyReplicasTest, SimpleCollectionCreateDeleteTest, SplitByPrefixTest, AutoAddReplicasPlanActionTest, ComputePlanActionTest, TriggerSetPropertiesIntegrationTest, TestSimClusterStateProvider, TestSimExecutePlanAction, TestSimExtremeIndexing, TestSimGenericDistributedQueue, ZkStateReaderTest, ZkStateWriterTest, ConfigureRecoveryStrategyTest, CoreSorterTest, DirectoryFactoriesTest, DirectoryFactoryTest, PluginInfoTest, QueryResultKeyTest, TestCoreContainer, TestLazyCores, FieldAnalysisRequestHandlerTest, PingRequestHandlerTest, TestSolrConfigHandlerConcurrent, AutoscalingHistoryHandlerTest, CoreAdminCreateDiscoverTest, CoreAdminHandlerTest, LukeRequestHandlerTest, SplitHandlerTest, ThreadDumpHandlerTest, ZookeeperStatusHandlerTest, CustomHighlightComponentTest, DistributedQueryComponentCustomSortTest]
   [junit4] Completed [701/952 (1!)] on J1 in 7.51s, 1 test, 1 error <<< FAILURES!

[...truncated 55053 lines...]