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 2020/06/16 10:30:24 UTC
[JENKINS] Lucene-Solr-NightlyTests-master - Build # 2225 - Unstable
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/2225/
1 tests failed.
FAILED: org.apache.solr.search.TestReRankQParserPlugin.testMinExactCount
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at __randomizedtesting.SeedInfo.seed([1125184F5D1139D9:64A522D7B2CCC7AE]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:1009)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:969)
at org.apache.solr.search.TestReRankQParserPlugin.testMinExactCount(TestReRankQParserPlugin.java:675)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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 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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:834)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result/doc[1]/str[@name='id'][.='0']
xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">1</int></lst><result name="response" numFound="200" start="0" numFoundExact="true"><doc><str name="id">126</str></doc><doc><str name="id">127</str></doc><doc><str name="id">128</str></doc><doc><str name="id">129</str></doc><doc><str name="id">130</str></doc><doc><str name="id">131</str></doc><doc><str name="id">132</str></doc><doc><str name="id">133</str></doc><doc><str name="id">134</str></doc><doc><str name="id">117</str></doc></result>
</response>
request was:q=field_t:0&start=0&rows=10&fl=id
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:1002)
... 41 more
Build Log:
[...truncated 12808 lines...]
[junit4] Suite: org.apache.solr.search.TestReRankQParserPlugin
[junit4] 2> 1253668 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1253669 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1253675 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.search.TestReRankQParserPlugin_1125184F5D1139D9-001/data-dir-74-001
[junit4] 2> 1253676 WARN (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 1253677 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1253679 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1253679 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1253688 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1253713 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.s.IndexSchema Schema name=example
[junit4] 2> 1253816 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.s.IndexSchema Loaded schema example/1.1 with uniqueid field id
[junit4] 2> 1255137 WARN (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.f.DistribPackageStore Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr]. Features requiring this directory may fail.
[junit4] 2> => java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/filestore" "write")
[junit4] 2> at java.base/java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
[junit4] 2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/filestore" "write")
[junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:?]
[junit4] 2> at java.security.AccessController.checkPermission(AccessController.java:897) ~[?:?]
[junit4] 2> at java.lang.SecurityManager.checkPermission(SecurityManager.java:322) ~[?:?]
[junit4] 2> at java.lang.SecurityManager.checkWrite(SecurityManager.java:752) ~[?:?]
[junit4] 2> at java.io.File.mkdir(File.java:1323) ~[?:?]
[junit4] 2> at java.io.File.mkdirs(File.java:1355) ~[?:?]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.ensurePackageStoreDir(DistribPackageStore.java:523) ~[java/:?]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.<init>(DistribPackageStore.java:77) ~[java/:?]
[junit4] 2> at org.apache.solr.filestore.PackageStoreAPI.<init>(PackageStoreAPI.java:79) ~[java/:?]
[junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:637) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:170) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:142) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:148) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:111) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:817) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:807) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:668) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:657) ~[java/:?]
[junit4] 2> at org.apache.solr.search.TestReRankQParserPlugin.beforeClass(TestReRankQParserPlugin.java:37) ~[test/:?]
[junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
[junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
[junit4] 2> at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:882) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.jar:?]
[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.6.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.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:834) [?:?]
[junit4] 2> 1255138 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1255141 WARN (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@19f7ec02[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1255144 WARN (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@19f7ec02[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1255151 WARN (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@145a21d5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1255151 WARN (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@145a21d5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1255152 WARN (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-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> 1255192 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1255192 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1255208 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61182f95
[junit4] 2> 1255214 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61182f95
[junit4] 2> 1255214 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61182f95
[junit4] 2> 1255220 INFO (coreLoadExecutor-7263-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1255235 INFO (coreLoadExecutor-7263-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=example
[junit4] 2> 1255271 INFO (coreLoadExecutor-7263-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema example/1.1 with uniqueid field id
[junit4] 2> 1255278 INFO (coreLoadExecutor-7263-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1255278 INFO (coreLoadExecutor-7263-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@61182f95
[junit4] 2> 1255278 INFO (coreLoadExecutor-7263-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.search.TestReRankQParserPlugin_1125184F5D1139D9-001/data-dir-74-001/]
[junit4] 2> 1255280 INFO (coreLoadExecutor-7263-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@174923f
[junit4] 2> 1255341 INFO (coreLoadExecutor-7263-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1255341 INFO (coreLoadExecutor-7263-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1255343 INFO (coreLoadExecutor-7263-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1255343 INFO (coreLoadExecutor-7263-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1255345 INFO (coreLoadExecutor-7263-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@830aadb
[junit4] 2> 1255349 WARN (coreLoadExecutor-7263-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf ; switching to use InMemory storage instead.
[junit4] 2> 1255349 INFO (coreLoadExecutor-7263-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1255349 INFO (coreLoadExecutor-7263-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct, classname=DirectSolrSpellChecker, field=a_s, minQueryLength=3}
[junit4] 2> 1255350 INFO (coreLoadExecutor-7263-thread-1) [ x:collection1 ] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/elevate.xml
[junit4] 2> 1255361 INFO (coreLoadExecutor-7263-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1255361 INFO (coreLoadExecutor-7263-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1669644528159555584
[junit4] 2> 1255363 INFO (searcherExecutor-7265-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1255363 INFO (searcherExecutor-7265-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1255363 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1255368 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMinExactCount
[junit4] 2> 1255369 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1255370 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 1
[junit4] 2> 1255376 INFO (searcherExecutor-7265-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1255376 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 1255378 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:* (-1669644528176332800)} 0 1
[junit4] 2> 1255379 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 1255384 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0 (1669644528179478528)]} 0 3
[junit4] 2> 1255386 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1 (1669644528183672832)]} 0 1
[junit4] 2> 1255390 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2 (1669644528187867136)]} 0 1
[junit4] 2> 1255391 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3 (1669644528189964288)]} 0 0
[junit4] 2> 1255393 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4 (1669644528191012864)]} 0 2
[junit4] 2> 1255395 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5 (1669644528193110016)]} 0 1
[junit4] 2> 1255396 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6 (1669644528195207168)]} 0 0
[junit4] 2> 1255397 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7 (1669644528196255744)]} 0 0
[junit4] 2> 1255409 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8 (1669644528198352896)]} 0 11
[junit4] 2> 1255412 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9 (1669644528210935808)]} 0 1
[junit4] 2> 1255413 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[10 (1669644528213032960)]} 0 0
[junit4] 2> 1255413 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[11 (1669644528214081536)]} 0 0
[junit4] 2> 1255414 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[12 (1669644528215130112)]} 0 0
[junit4] 2> 1255415 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[13 (1669644528215130113)]} 0 0
[junit4] 2> 1255416 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[14 (1669644528216178688)]} 0 0
[junit4] 2> 1255417 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[15 (1669644528217227264)]} 0 0
[junit4] 2> 1255418 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[16 (1669644528218275840)]} 0 1
[junit4] 2> 1255432 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[17 (1669644528220372992)]} 0 12
[junit4] 2> 1255436 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[18 (1669644528235053056)]} 0 3
[junit4] 2> 1255437 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[19 (1669644528238198784)]} 0 0
[junit4] 2> 1255438 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[20 (1669644528240295936)]} 0 1
[junit4] 2> 1255439 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[21 (1669644528240295937)]} 0 0
[junit4] 2> 1255440 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[22 (1669644528241344512)]} 0 0
[junit4] 2> 1255442 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[23 (1669644528243441664)]} 0 0
[junit4] 2> 1255443 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[24 (1669644528244490240)]} 0 0
[junit4] 2> 1255443 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[25 (1669644528245538816)]} 0 0
[junit4] 2> 1255461 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[26 (1669644528246587392)]} 0 17
[junit4] 2> 1255463 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[27 (1669644528265461760)]} 0 1
[junit4] 2> 1255464 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[28 (1669644528267558912)]} 0 0
[junit4] 2> 1255465 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[29 (1669644528268607488)]} 0 0
[junit4] 2> 1255466 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[30 (1669644528269656064)]} 0 0
[junit4] 2> 1255467 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[31 (1669644528270704640)]} 0 0
[junit4] 2> 1255469 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[32 (1669644528271753216)]} 0 0
[junit4] 2> 1255470 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[33 (1669644528272801792)]} 0 1
[junit4] 2> 1255471 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[34 (1669644528273850368)]} 0 0
[junit4] 2> 1255484 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[35 (1669644528274898944)]} 0 12
[junit4] 2> 1255486 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[36 (1669644528288530432)]} 0 2
[junit4] 2> 1255487 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[37 (1669644528291676160)]} 0 0
[junit4] 2> 1255488 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[38 (1669644528292724736)]} 0 0
[junit4] 2> 1255489 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[39 (1669644528292724737)]} 0 0
[junit4] 2> 1255490 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[40 (1669644528293773312)]} 0 0
[junit4] 2> 1255490 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[41 (1669644528294821888)]} 0 0
[junit4] 2> 1255491 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[42 (1669644528295870464)]} 0 0
[junit4] 2> 1255492 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[43 (1669644528296919040)]} 0 0
[junit4] 2> 1255505 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[44 (1669644528297967616)]} 0 12
[junit4] 2> 1255507 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[45 (1669644528311599104)]} 0 1
[junit4] 2> 1255508 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[46 (1669644528312647680)]} 0 0
[junit4] 2> 1255509 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[47 (1669644528313696256)]} 0 0
[junit4] 2> 1255510 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[48 (1669644528314744832)]} 0 1
[junit4] 2> 1255511 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[49 (1669644528316841984)]} 0 1
[junit4] 2> 1255512 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[50 (1669644528317890560)]} 0 0
[junit4] 2> 1255513 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[51 (1669644528318939136)]} 0 0
[junit4] 2> 1255514 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[52 (1669644528318939137)]} 0 0
[junit4] 2> 1255526 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[53 (1669644528319987712)]} 0 11
[junit4] 2> 1255528 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[54 (1669644528333619200)]} 0 1
[junit4] 2> 1255529 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[55 (1669644528334667776)]} 0 0
[junit4] 2> 1255529 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[56 (1669644528335716352)]} 0 0
[junit4] 2> 1255530 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[57 (1669644528336764928)]} 0 0
[junit4] 2> 1255531 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[58 (1669644528337813504)]} 0 0
[junit4] 2> 1255532 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[59 (1669644528337813505)]} 0 0
[junit4] 2> 1255533 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[60 (1669644528338862080)]} 0 1
[junit4] 2> 1255534 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[61 (1669644528339910656)]} 0 0
[junit4] 2> 1255547 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[62 (1669644528340959232)]} 0 12
[junit4] 2> 1255548 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[63 (1669644528354590720)]} 0 1
[junit4] 2> 1255549 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[64 (1669644528355639296)]} 0 0
[junit4] 2> 1255550 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[65 (1669644528356687872)]} 0 0
[junit4] 2> 1255550 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[66 (1669644528357736448)]} 0 0
[junit4] 2> 1255551 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[67 (1669644528358785024)]} 0 0
[junit4] 2> 1255552 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[68 (1669644528358785025)]} 0 0
[junit4] 2> 1255553 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[69 (1669644528359833600)]} 0 0
[junit4] 2> 1255553 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[70 (1669644528360882176)]} 0 0
[junit4] 2> 1255567 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[71 (1669644528361930752)]} 0 12
[junit4] 2> 1255568 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[72 (1669644528375562240)]} 0 1
[junit4] 2> 1255569 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[73 (1669644528376610816)]} 0 0
[junit4] 2> 1255570 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[74 (1669644528377659392)]} 0 0
[junit4] 2> 1255570 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[75 (1669644528378707968)]} 0 0
[junit4] 2> 1255676 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[76 (1669644528379756544)]} 0 105
[junit4] 2> 1255677 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[77 (1669644528489857024)]} 0 0
[junit4] 2> 1255678 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[78 (1669644528490905600)]} 0 0
[junit4] 2> 1255679 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[79 (1669644528491954176)]} 0 0
[junit4] 2> 1255700 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[80 (1669644528493002752)]} 0 20
[junit4] 2> 1255701 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[81 (1669644528515022848)]} 0 1
[junit4] 2> 1255702 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[82 (1669644528517120000)]} 0 0
[junit4] 2> 1255703 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[83 (1669644528517120001)]} 0 0
[junit4] 2> 1255703 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[84 (1669644528518168576)]} 0 0
[junit4] 2> 1255704 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[85 (1669644528519217152)]} 0 0
[junit4] 2> 1255705 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[86 (1669644528519217153)]} 0 0
[junit4] 2> 1255706 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[87 (1669644528520265728)]} 0 0
[junit4] 2> 1255706 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[88 (1669644528521314304)]} 0 0
[junit4] 2> 1255874 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[89 (1669644528521314305)]} 0 167
[junit4] 2> 1255881 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[90 (1669644528702717952)]} 0 2
[junit4] 2> 1255885 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[91 (1669644528705863680)]} 0 2
[junit4] 2> 1255886 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[92 (1669644528709009408)]} 0 0
[junit4] 2> 1255887 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[93 (1669644528710057984)]} 0 0
[junit4] 2> 1255888 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[94 (1669644528711106560)]} 0 0
[junit4] 2> 1255889 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[95 (1669644528713203712)]} 0 0
[junit4] 2> 1255890 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[96 (1669644528713203713)]} 0 0
[junit4] 2> 1255891 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[97 (1669644528714252288)]} 0 0
[junit4] 2> 1255908 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[98 (1669644528715300864)]} 0 17
[junit4] 2> 1255911 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[99 (1669644528734175232)]} 0 1
[junit4] 2> 1255911 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[100 (1669644528736272384)]} 0 0
[junit4] 2> 1255913 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[101 (1669644528737320960)]} 0 1
[junit4] 2> 1255914 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[102 (1669644528738369536)]} 0 1
[junit4] 2> 1255915 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[103 (1669644528739418112)]} 0 0
[junit4] 2> 1255915 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[104 (1669644528740466688)]} 0 0
[junit4] 2> 1255916 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[105 (1669644528741515264)]} 0 0
[junit4] 2> 1255917 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[106 (1669644528741515265)]} 0 0
[junit4] 2> 1255935 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[107 (1669644528742563840)]} 0 18
[junit4] 2> 1255938 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[108 (1669644528762486784)]} 0 2
[junit4] 2> 1255938 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[109 (1669644528764583936)]} 0 0
[junit4] 2> 1255939 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[110 (1669644528765632512)]} 0 0
[junit4] 2> 1255940 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[111 (1669644528766681088)]} 0 0
[junit4] 2> 1255941 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[112 (1669644528767729664)]} 0 0
[junit4] 2> 1255942 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[113 (1669644528768778240)]} 0 0
[junit4] 2> 1255943 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[114 (1669644528769826816)]} 0 0
[junit4] 2> 1255944 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[115 (1669644528769826817)]} 0 0
[junit4] 2> 1255956 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[116 (1669644528770875392)]} 0 11
[junit4] 2> 1255957 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[117 (1669644528783458304)]} 0 1
[junit4] 2> 1255958 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[118 (1669644528785555456)]} 0 0
[junit4] 2> 1255959 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[119 (1669644528785555457)]} 0 0
[junit4] 2> 1255961 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[120 (1669644528786604032)]} 0 2
[junit4] 2> 1255962 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[121 (1669644528789749760)]} 0 0
[junit4] 2> 1255963 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[122 (1669644528789749761)]} 0 0
[junit4] 2> 1255964 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[123 (1669644528790798336)]} 0 0
[junit4] 2> 1255964 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[124 (1669644528791846912)]} 0 0
[junit4] 2> 1255980 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[125 (1669644528792895488)]} 0 15
[junit4] 2> 1255983 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[126 (1669644528809672704)]} 0 1
[junit4] 2> 1255983 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[127 (1669644528811769856)]} 0 0
[junit4] 2> 1255984 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[128 (1669644528812818432)]} 0 0
[junit4] 2> 1255985 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[129 (1669644528812818433)]} 0 0
[junit4] 2> 1255986 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[130 (1669644528813867008)]} 0 0
[junit4] 2> 1255986 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[131 (1669644528814915584)]} 0 0
[junit4] 2> 1255987 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[132 (1669644528815964160)]} 0 0
[junit4] 2> 1255988 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[133 (1669644528815964161)]} 0 0
[junit4] 2> 1256011 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[134 (1669644528817012736)]} 0 23
[junit4] 2> 1256015 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[135 (1669644528842178560)]} 0 2
[junit4] 2> 1256016 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[136 (1669644528845324288)]} 0 0
[junit4] 2> 1256017 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[137 (1669644528847421440)]} 0 0
[junit4] 2> 1256018 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[138 (1669644528848470016)]} 0 0
[junit4] 2> 1256019 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[139 (1669644528849518592)]} 0 0
[junit4] 2> 1256020 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[140 (1669644528850567168)]} 0 0
[junit4] 2> 1256021 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[141 (1669644528850567169)]} 0 0
[junit4] 2> 1256021 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[142 (1669644528851615744)]} 0 0
[junit4] 2> 1256031 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[143 (1669644528851615745)]} 0 9
[junit4] 2> 1256032 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[144 (1669644528862101504)]} 0 1
[junit4] 2> 1256033 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[145 (1669644528864198656)]} 0 0
[junit4] 2> 1256034 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[146 (1669644528864198657)]} 0 0
[junit4] 2> 1256034 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[147 (1669644528865247232)]} 0 0
[junit4] 2> 1256035 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[148 (1669644528866295808)]} 0 0
[junit4] 2> 1256036 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[149 (1669644528866295809)]} 0 0
[junit4] 2> 1256037 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[150 (1669644528867344384)]} 0 0
[junit4] 2> 1256038 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[151 (1669644528868392960)]} 0 1
[junit4] 2> 1256158 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[152 (1669644528869441536)]} 0 119
[junit4] 2> 1256161 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[153 (1669644528995270656)]} 0 2
[junit4] 2> 1256162 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[154 (1669644528999464960)]} 0 0
[junit4] 2> 1256163 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[155 (1669644529000513536)]} 0 0
[junit4] 2> 1256165 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[156 (1669644529001562112)]} 0 1
[junit4] 2> 1256166 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[157 (1669644529002610688)]} 0 0
[junit4] 2> 1256167 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[158 (1669644529003659264)]} 0 0
[junit4] 2> 1256170 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[159 (1669644529005756416)]} 0 1
[junit4] 2> 1256172 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[160 (1669644529007853568)]} 0 1
[junit4] 2> 1256190 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[161 (1669644529010999296)]} 0 17
[junit4] 2> 1256191 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[162 (1669644529028825088)]} 0 1
[junit4] 2> 1256192 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[163 (1669644529029873664)]} 0 0
[junit4] 2> 1256193 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[164 (1669644529030922240)]} 0 0
[junit4] 2> 1256193 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[165 (1669644529031970816)]} 0 0
[junit4] 2> 1256194 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[166 (1669644529031970817)]} 0 0
[junit4] 2> 1256195 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[167 (1669644529033019392)]} 0 0
[junit4] 2> 1256196 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[168 (1669644529034067968)]} 0 0
[junit4] 2> 1256196 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[169 (1669644529035116544)]} 0 0
[junit4] 2> 1256208 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[170 (1669644529036165120)]} 0 11
[junit4] 2> 1256210 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[171 (1669644529048748032)]} 0 1
[junit4] 2> 1256210 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[172 (1669644529049796608)]} 0 0
[junit4] 2> 1256211 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[173 (1669644529050845184)]} 0 0
[junit4] 2> 1256212 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[174 (1669644529050845185)]} 0 0
[junit4] 2> 1256213 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[175 (1669644529051893760)]} 0 0
[junit4] 2> 1256213 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[176 (1669644529052942336)]} 0 0
[junit4] 2> 1256214 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[177 (1669644529053990912)]} 0 0
[junit4] 2> 1256215 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[178 (1669644529053990913)]} 0 0
[junit4] 2> 1256226 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[179 (1669644529055039488)]} 0 10
[junit4] 2> 1256227 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[180 (1669644529066573824)]} 0 1
[junit4] 2> 1256228 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[181 (1669644529067622400)]} 0 0
[junit4] 2> 1256228 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[182 (1669644529068670976)]} 0 0
[junit4] 2> 1256229 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[183 (1669644529068670977)]} 0 0
[junit4] 2> 1256230 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[184 (1669644529069719552)]} 0 0
[junit4] 2> 1256230 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[185 (1669644529070768128)]} 0 0
[junit4] 2> 1256231 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[186 (1669644529070768129)]} 0 0
[junit4] 2> 1256231 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[187 (1669644529071816704)]} 0 0
[junit4] 2> 1256242 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[188 (1669644529072865280)]} 0 10
[junit4] 2> 1256243 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[189 (1669644529083351040)]} 0 1
[junit4] 2> 1256244 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[190 (1669644529085448192)]} 0 0
[junit4] 2> 1256245 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[191 (1669644529085448193)]} 0 0
[junit4] 2> 1256245 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[192 (1669644529086496768)]} 0 0
[junit4] 2> 1256246 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[193 (1669644529087545344)]} 0 0
[junit4] 2> 1256247 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[194 (1669644529087545345)]} 0 0
[junit4] 2> 1256247 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[195 (1669644529088593920)]} 0 0
[junit4] 2> 1256248 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[196 (1669644529089642496)]} 0 0
[junit4] 2> 1256262 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[197 (1669644529089642497)]} 0 14
[junit4] 2> 1256267 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[198 (1669644529105371136)]} 0 3
[junit4] 2> 1256268 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[199 (1669644529109565440)]} 0 0
[junit4] 2> 1256311 INFO (searcherExecutor-7265-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1256311 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 42
[junit4] 2> 1256313 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=field_t:0&start=0&rows=10&fl=id} hits=200 status=0 QTime=1
[junit4] 2> 1256314 ERROR (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ ] o.a.s.SolrTestCaseJ4 REQUEST FAILED: xpath=//result/doc[1]/str[@name='id'][.='0']
[junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] 2> <response>
[junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">1</int></lst><result name="response" numFound="200" start="0" numFoundExact="true"><doc><str name="id">126</str></doc><doc><str name="id">127</str></doc><doc><str name="id">128</str></doc><doc><str name="id">129</str></doc><doc><str name="id">130</str></doc><doc><str name="id">131</str></doc><doc><str name="id">132</str></doc><doc><str name="id">133</str></doc><doc><str name="id">134</str></doc><doc><str name="id">117</str></doc></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:q=field_t:0&start=0&rows=10&fl=id
[junit4] 2> 1256314 ERROR (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ ] o.a.s.SolrTestCaseJ4 REQUEST FAILED: q=field_t:0&start=0&rows=10&fl=id:java.lang.RuntimeException: REQUEST FAILED: xpath=//result/doc[1]/str[@name='id'][.='0']
[junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] 2> <response>
[junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">1</int></lst><result name="response" numFound="200" start="0" numFoundExact="true"><doc><str name="id">126</str></doc><doc><str name="id">127</str></doc><doc><str name="id">128</str></doc><doc><str name="id">129</str></doc><doc><str name="id">130</str></doc><doc><str name="id">131</str></doc><doc><str name="id">132</str></doc><doc><str name="id">133</str></doc><doc><str name="id">134</str></doc><doc><str name="id">117</str></doc></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:q=field_t:0&start=0&rows=10&fl=id
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:1002)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:969)
[junit4] 2> at org.apache.solr.search.TestReRankQParserPlugin.testMinExactCount(TestReRankQParserPlugin.java:675)
[junit4] 2> at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.base/java.lang.reflect.Method.invoke(Method.java:566)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] 2>
[junit4] 2> 1256316 INFO (TEST-TestReRankQParserPlugin.testMinExactCount-seed#[1125184F5D1139D9]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMinExactCount
[junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestReRankQParserPlugin -Dtests.method=testMinExactCount -Dtests.seed=1125184F5D1139D9 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.locale=dsb -Dtests.timezone=Pacific/Truk -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.95s J0 | TestReRankQParserPlugin.testMinExactCount <<<
[junit4] > Throwable #1: java.lang.RuntimeException: Exception during query
[junit4] > at __randomizedtesting.SeedInfo.seed([1125184F5D1139D9:64A522D7B2CCC7AE]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:1009)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:969)
[junit4] > at org.apache.solr.search.TestReRankQParserPlugin.testMinExactCount(TestReRankQParserPlugin.java:675)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:566)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] > Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result/doc[1]/str[@name='id'][.='0']
[junit4] > xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] > <response>
[junit4] > <lst name="responseHeader"><int name="status">0</int><int name="QTime">1</int></lst><result name="response" numFound="200" start="0" numFoundExact="true"><doc><str name="id">126</str></doc><doc><str name="id">127</str></doc><doc><str name="id">128</str></doc><doc><str name="id">129</str></doc><doc><str name="id">130</str></doc><doc><str name="id">131</str></doc><doc><str name="id">132</str></doc><doc><str name="id">133</str></doc><doc><str name="id">134</str></doc><doc><str name="id">117</str></doc></result>
[junit4] > </response>
[junit4] > request was:q=field_t:0&start=0&rows=10&fl=id
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:1002)
[junit4] > ... 41 more
[junit4] 2> 1256323 INFO (TEST-TestReRankQParserPlugin.testRerankQueryParsingShouldFailWithoutMandatoryReRankQueryParameter-seed#[1125184F5D1139D9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRerankQueryParsingShouldFailWithoutMandatoryReRankQueryParameter
[junit4] 2> 1256323 INFO (TEST-TestReRankQParserPlugin.testRerankQueryParsingShouldFailWithoutMandatoryReRankQueryParameter-seed#[1125184F5D1139D9]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1256348 INFO (TEST-TestReRankQParserPlugin.testRerankQueryParsingShouldFailWithoutMandatoryReRankQueryParameter-seed#[1125184F5D1139D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 24
[junit4] 2> 1256360 INFO (searcherExecutor-7265-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1256370 INFO (TEST-TestReRankQParserPlugin.testRerankQueryParsingShouldFailWithoutMandatoryReRankQueryParameter-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 22
[junit4] 2> 1256371 INFO (TEST-TestReRankQParserPlugin.testRerankQueryParsingShouldFailWithoutMandatoryReRankQueryParameter-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:* (-1669644529217568768)} 0 1
[junit4] 2> 1256372 INFO (TEST-TestReRankQParserPlugin.testRerankQueryParsingShouldFailWithoutMandatoryReRankQueryParameter-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 1256374 INFO (TEST-TestReRankQParserPlugin.testRerankQueryParsingShouldFailWithoutMandatoryReRankQueryParameter-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1 (1669644529219665921)]} 0 1
[junit4] 2> 1256392 INFO (searcherExecutor-7265-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1256392 INFO (TEST-TestReRankQParserPlugin.testRerankQueryParsingShouldFailWithoutMandatoryReRankQueryParameter-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 17
[junit4] 2> 1256394 INFO (TEST-TestReRankQParserPlugin.testRerankQueryParsingShouldFailWithoutMandatoryReRankQueryParameter-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2 (1669644529240637440)]} 0 1
[junit4] 2> 1256411 INFO (searcherExecutor-7265-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1256411 INFO (TEST-TestReRankQParserPlugin.testRerankQueryParsingShouldFailWithoutMandatoryReRankQueryParameter-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 16
[junit4] 2> 1256417 INFO (TEST-TestReRankQParserPlugin.testRerankQueryParsingShouldFailWithoutMandatoryReRankQueryParameter-seed#[1125184F5D1139D9]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching reRankQuery parameter is mandatory
[junit4] 2> 1256417 INFO (TEST-TestReRankQParserPlugin.testRerankQueryParsingShouldFailWithoutMandatoryReRankQueryParameter-seed#[1125184F5D1139D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={rq={!rerank+reRankQuery%3D$rqq+reRankDocs%3D200}&q=term_s:YYYY&start=0&rows=2} status=400 QTime=5
[junit4] 2> 1256418 INFO (TEST-TestReRankQParserPlugin.testRerankQueryParsingShouldFailWithoutMandatoryReRankQueryParameter-seed#[1125184F5D1139D9]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRerankQueryParsingShouldFailWithoutMandatoryReRankQueryParameter
[junit4] 2> 1256421 INFO (TEST-TestReRankQParserPlugin.testOverRank-seed#[1125184F5D1139D9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testOverRank
[junit4] 2> 1256421 INFO (TEST-TestReRankQParserPlugin.testOverRank-seed#[1125184F5D1139D9]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1256422 INFO (TEST-TestReRankQParserPlugin.testOverRank-seed#[1125184F5D1139D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 0
[junit4] 2> 1256428 INFO (searcherExecutor-7265-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1256432 INFO (TEST-TestReRankQParserPlugin.testOverRank-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 9
[junit4] 2> 1256433 INFO (TEST-TestReRankQParserPlugin.testOverRank-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:* (-1669644529282580480)} 0 1
[junit4] 2> 1256434 INFO (TEST-TestReRankQParserPlugin.testOverRank-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 1256435 INFO (TEST-TestReRankQParserPlugin.testOverRank-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1 (1669644529284677633)]} 0 1
[junit4] 2> 1256436 INFO (TEST-TestReRankQParserPlugin.testOverRank-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2 (1669644529286774784)]} 0 0
[junit4] 2> 1256436 INFO (TEST-TestReRankQParserPlugin.testOverRank-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3 (1669644529286774785)]} 0 0
[junit4] 2> 1256437 INFO (TEST-TestReRankQParserPlugin.testOverRank-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4 (1669644529286774786)]} 0 0
[junit4] 2> 1256437 INFO (TEST-TestReRankQParserPlugin.testOverRank-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5 (1669644529287823360)]} 0 0
[junit4] 2> 1256437 INFO (TEST-TestReRankQParserPlugin.testOverRank-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6 (1669644529287823361)]} 0 0
[junit4] 2> 1256437 INFO (TEST-TestReRankQParserPlugin.testOverRank-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7 (1669644529287823362)]} 0 0
[junit4] 2> 1256438 INFO (TEST-TestReRankQParserPlugin.testOverRank-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8 (1669644529288871936)]} 0 0
[junit4] 2> 1256444 INFO (TEST-TestReRankQParserPlugin.testOverRank-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9 (1669644529288871937)]} 0 6
[junit4] 2> 1256445 INFO (TEST-TestReRankQParserPlugin.testOverRank-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[10 (1669644529295163392)]} 0 0
[junit4] 2> 1256445 INFO (TEST-TestReRankQParserPlugin.testOverRank-seed#[1125184F5D1139D9]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[11 (1669644529296211968)]} 0 0
[junit4] 2> 1256466 INFO (searcherExecutor-7265-thread-1-processing-x:collection1) [ x:collection1
[...truncated too long message...]
bqq1=id:1^10+id:2^20+id:3^30+id:4^40+id:5^50+id:6^60&rqq=test_ti:50^1000&fl=id,score&start=0&rows=10&qt=/elevate&elevateIds=4,1} hits=6 status=0 QTime=1
[junit4] 2> 1256688 INFO (TEST-TestReRankQParserPlugin.testReRankQueries-seed#[1125184F5D1139D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={rq={!rerank+reRankQuery%3D$rqq+reRankDocs%3D3+reRankWeight%3D2}&q={!edismax+bq%3D$bqq1}*:*&bqq1=id:1^10+id:2^20+id:3^30+id:4^40+id:5^50+id:6^60&rqq=test_ti:50^1000&fl=id,score&start=4&rows=10&qt=/elevate&elevateIds=4,1} hits=6 status=0 QTime=0
[junit4] 2> 1256690 INFO (TEST-TestReRankQParserPlugin.testReRankQueries-seed#[1125184F5D1139D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={rq={!rerank+reRankQuery%3D$rqq+reRankDocs%3D3+reRankWeight%3D2}&q={!edismax+bq%3D$bqq1}nada&bqq1=id:1^10+id:2^20+id:3^30+id:4^40+id:5^50+id:6^60&rqq=test_ti:50^1000&fl=id,score&start=4&rows=10&qt=/elevate&elevateIds=4,1} hits=2 status=0 QTime=1
[junit4] 2> 1256691 INFO (TEST-TestReRankQParserPlugin.testReRankQueries-seed#[1125184F5D1139D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={rq={!rerank+reRankQuery%3D$rqq+reRankDocs%3D1+reRankWeight%3D2}&q={!edismax+bq%3D$bqq1}*:*&bqq1=id:1^10+id:2^20+id:3^30+id:4^40+id:5^50+id:6^60&rqq=test_ti:50^1000&fl=id,score&start=0&rows=10} hits=6 status=0 QTime=0
[junit4] 2> 1256692 INFO (TEST-TestReRankQParserPlugin.testReRankQueries-seed#[1125184F5D1139D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={rq={!rerank+reRankQuery%3D$rqq+reRankDocs%3D0+reRankWeight%3D2}&q={!edismax+bq%3D$bqq1}*:*&bqq1=id:1^10+id:2^20+id:3^30+id:4^40+id:5^50+id:6^60&rqq=test_ti:50^1000&fl=id,score&start=0&rows=10} hits=6 status=0 QTime=0
[junit4] 2> 1256693 INFO (TEST-TestReRankQParserPlugin.testReRankQueries-seed#[1125184F5D1139D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={rq={!rerank+reRankQuery%3D$rqq+reRankDocs%3D2+reRankWeight%3D2}&q={!edismax+bq%3D$bqq1}*:*&bqq1=id:1^10+id:2^20+id:3^30+id:4^40+id:5^50+id:6^60&rqq=test_ti:4^1000&fl=id,score&start=0&rows=10} hits=6 status=0 QTime=0
[junit4] 2> 1256695 INFO (TEST-TestReRankQParserPlugin.testReRankQueries-seed#[1125184F5D1139D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={rq={!rerank+reRankQuery%3D$rqq+reRankDocs%3D6+reRankWeight%3D0}&q={!edismax+bq%3D$bqq1}*:*&bqq1=id:1^10+id:2^20+id:3^30+id:4^40+id:5^50+id:6^60&rqq=test_ti:50^1000&fl=id,score&start=0&rows=5} hits=6 status=0 QTime=0
[junit4] 2> 1256697 INFO (TEST-TestReRankQParserPlugin.testReRankQueries-seed#[1125184F5D1139D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={rq={!rerank+reRankQuery%3D$rqq+reRankDocs%3D6}&q=test_ti:[0+TO+2000]&rqq=id:1^10+id:2^20+id:3^30+id:4^40+id:5^50+id:6^60&fl=id,score&start=0&rows=6} hits=5 status=0 QTime=2
[junit4] 2> 1256700 INFO (TEST-TestReRankQParserPlugin.testReRankQueries-seed#[1125184F5D1139D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={rq={!rerank+reRankQuery%3D$rqq+reRankDocs%3D6}&q=test_ti:[0+TO+2000]&rqq=id:1^10+id:2^20+id:3^30+id:4^40+id:5^50+id:6^60&fl=id,score&start=0&rows=6} hits=5 status=0 QTime=0
[junit4] 2> 1256701 INFO (TEST-TestReRankQParserPlugin.testReRankQueries-seed#[1125184F5D1139D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={rq={!rerank+reRankQuery%3D$rqq+reRankDocs%3D6}&q={!func}id_i&rqq=id:1^%3D10+id:2^%3D40+id:3^%3D30+id:4^%3D40+id:5^%3D50+id:6^%3D60&fl=id,score&start=0&rows=6} hits=6 status=0 QTime=0
[junit4] 2> 1256702 INFO (TEST-TestReRankQParserPlugin.testReRankQueries-seed#[1125184F5D1139D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={rq={!rerank+reRankQuery%3D$rqq+reRankDocs%3D3+reRankWeight%3D2}&q=id:1^10+id:2^20+id:3^30+id:4^40+id:5^50+id:6^60&rqq=id:1^1000&fl=id,score&start=4&rows=5} hits=6 status=0 QTime=0
[junit4] 2> 1256703 INFO (TEST-TestReRankQParserPlugin.testReRankQueries-seed#[1125184F5D1139D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={rq={!rerank+reRankQuery%3D$rqq+reRankDocs%3D6+reRankWeight%3D2}&q=id:1^10+id:2^20+id:3^30+id:4^40+id:5^50&rqq=id:1^1000&fl=id,score&start=0&rows=1} hits=5 status=0 QTime=0
[junit4] 2> 1256704 INFO (TEST-TestReRankQParserPlugin.testReRankQueries-seed#[1125184F5D1139D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={rq={!rerank+reRankQuery%3D$rqq+reRankDocs%3D3+reRankWeight%3D2}&q=term_s:NNNN&rqq=id:1^1000&fl=id,score&start=4&rows=5} hits=0 status=0 QTime=0
[junit4] 2> 1256704 INFO (TEST-TestReRankQParserPlugin.testReRankQueries-seed#[1125184F5D1139D9]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testReRankQueries
[junit4] 2> 1256705 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1256705 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=460130022
[junit4] 2> 1256706 INFO (coreCloseExecutor-7271-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@12126ab0
[junit4] 2> 1256706 INFO (coreCloseExecutor-7271-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@12126ab0
[junit4] 2> 1256706 INFO (coreCloseExecutor-7271-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@737b36ca: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@6705913
[junit4] 2> 1256722 INFO (coreCloseExecutor-7271-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 1256728 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 1256728 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@60331b0c: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@614fc478
[junit4] 2> 1256733 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 1256733 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@46678f08: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@70ea0963
[junit4] 2> 1256736 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 1256736 INFO (SUITE-TestReRankQParserPlugin-seed#[1125184F5D1139D9]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7fb0a51f: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@1531b17
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.search.TestReRankQParserPlugin_1125184F5D1139D9-001
[junit4] 2> NOTE: test params are: codec=Lucene86, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@558468a0), locale=dsb, timezone=Pacific/Truk
[junit4] 2> NOTE: Linux 4.15.0-54-generic amd64/Oracle Corporation 11.0.4 (64-bit)/cpus=4,threads=1,free=95731400,total=489684992
[junit4] 2> NOTE: All tests run in this JVM: [SolrPluginUtilsTest, LeaderFailoverAfterPartitionTest, TestBlendedInfixSuggestions, ZkSolrClientTest, BJQParserTest, TestSystemIdResolver, EchoParamsTest, TestHashQParserPlugin, SystemCollectionCompatTest, TestRandomRequestDistribution, TestTlogReplica, TestJsonFacetErrors, TestMissingGroups, IgnoreCommitOptimizeUpdateProcessorFactoryTest, ExecutePlanActionTest, CheckHdfsIndexTest, SparseHLLTest, SchemaVersionSpecificBehaviorTest, TestStandardQParsers, TestOrdValues, TestReplicationHandler, TestCloudJSONFacetJoinDomain, TestCloudManagedSchema, TestDistribPackageStore, TestChildDocTransformerHierarchy, TestSimNodeAddedTrigger, TestPartialUpdateDeduplication, TestLegacyNumericRangeQueryBuilder, TestSimDistribStateManager, NestedAtomicUpdateTest, TestRecoveryHdfs, NumberUtilsTest, TestJmxIntegration, TestSolrCoreProperties, TestSolrJ, TestCloudRecovery, DistanceUnitsTest, DirectoryFactoryTest, TestConfigOverlay, TestSolrCloudWithHadoopAuthPlugin, VMParamsZkACLAndCredentialsProvidersTest, TestSurroundQueryParser, TestSchemalessBufferedUpdates, SynonymTokenizerTest, SpellingQueryConverterTest, SolrCoreMetricManagerTest, TestCrossCoreJoin, TestDistributedStatsComponentCardinality, MultiThreadedOCPTest, XmlInterpolationTest, DocValuesMissingTest, TestIndexingPerformance, TestSSLRandomization, TestSolrConfigHandler, CoreMergeIndexesAdminHandlerTest, BasicAuthStandaloneTest, TestConfigSets, CircularListTest, PreAnalyzedFieldManagedSchemaCloudTest, CollectionReloadTest, TestUtilizeNode, SliceStateTest, TestSafeXMLParsing, AutoAddReplicasPlanActionTest, SplitHandlerTest, AnalyticsMergeStrategyTest, TestSubQueryTransformerCrossCore, LukeRequestHandlerTest, CachingDirectoryFactoryTest, TestFieldTypeResource, TestReRankQParserPlugin]
[junit4] Completed [209/910 (1!)] on J0 in 3.12s, 6 tests, 1 error <<< FAILURES!
[...truncated 54393 lines...]
[JENKINS] Lucene-Solr-NightlyTests-master - Build # 2226 - Still
Unstable
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/2226/
1 tests failed.
FAILED: org.apache.solr.cloud.RollingRestartTest.test
Error Message:
Timeout occurred while waiting response from server at: https://127.0.0.1:46804
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: https://127.0.0.1:46804
at __randomizedtesting.SeedInfo.seed([728B5D8C6DC94ED7:FADF6256C335232F]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:692)
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.impl.LBSolrClient.doRequest(LBSolrClient.java:370)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:298)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1155)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:916)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:850)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:210)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:227)
at org.apache.solr.cloud.RollingRestartTest.restartWithRolesTest(RollingRestartTest.java:74)
at org.apache.solr.cloud.RollingRestartTest.test(RollingRestartTest.java:53)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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 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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:834)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.base/java.net.SocketInputStream.socketRead0(Native Method)
at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:448)
at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68)
at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1104)
at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:823)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:571)
... 52 more
Build Log:
[...truncated 14074 lines...]
[junit4] Suite: org.apache.solr.cloud.RollingRestartTest
[junit4] 2> 4136164 INFO (SUITE-RollingRestartTest-seed#[728B5D8C6DC94ED7]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/server/solr/configsets/_default/conf'
[junit4] 2> 4136164 INFO (SUITE-RollingRestartTest-seed#[728B5D8C6DC94ED7]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/data-dir-189-001
[junit4] 2> 4136165 WARN (SUITE-RollingRestartTest-seed#[728B5D8C6DC94ED7]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=25 numCloses=25
[junit4] 2> 4136165 INFO (SUITE-RollingRestartTest-seed#[728B5D8C6DC94ED7]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 4136182 INFO (SUITE-RollingRestartTest-seed#[728B5D8C6DC94ED7]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 4136183 INFO (SUITE-RollingRestartTest-seed#[728B5D8C6DC94ED7]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 4136183 INFO (SUITE-RollingRestartTest-seed#[728B5D8C6DC94ED7]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 4136188 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 4136189 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 4136189 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 4136289 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.ZkTestServer start zk server on port: 36718
[junit4] 2> 4136289 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:36718
[junit4] 2> 4136289 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:36718
[junit4] 2> 4136289 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 36718
[junit4] 2> 4136291 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4136293 INFO (zkConnectionManagerCallback-21058-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4136293 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4136296 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4136297 INFO (zkConnectionManagerCallback-21060-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4136297 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4136298 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 4136309 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 4136316 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 4136318 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 4136320 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 4136321 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 4136323 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 4136333 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 4136335 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 4136357 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 4136358 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 4136361 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4136362 INFO (zkConnectionManagerCallback-21064-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4136362 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4136464 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 4137083 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 4137083 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 4137083 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.4+10-LTS
[junit4] 2> 4137084 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4137084 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4137084 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 4137084 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@17b2fe8a{/,null,AVAILABLE}
[junit4] 2> 4137086 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1dde924{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:34651}
[junit4] 2> 4137086 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.e.j.s.Server Started @4137125ms
[junit4] 2> 4137086 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/tempDir-001/control/data, hostPort=34651, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/control-001/cores, replicaType=NRT}
[junit4] 2> 4137086 ERROR (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4137086 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4137086 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 4137086 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4137086 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr
[junit4] 2> 4137086 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-06-17T10:43:09.618462Z
[junit4] 2> 4137087 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4137088 INFO (zkConnectionManagerCallback-21066-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4137088 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4137190 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 4137190 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/control-001/solr.xml
[junit4] 2> 4137194 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 4140989 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 4140991 WARN (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2afcb495[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4140991 WARN (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2afcb495[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4140995 WARN (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1972028d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4140995 WARN (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1972028d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4141005 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36718/solr
[junit4] 2> 4141005 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4141007 INFO (zkConnectionManagerCallback-21077-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4141007 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4141110 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [n:127.0.0.1:34651_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4141112 INFO (zkConnectionManagerCallback-21079-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4141112 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [n:127.0.0.1:34651_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4141413 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [n:127.0.0.1:34651_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:34651_
[junit4] 2> 4141414 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [n:127.0.0.1:34651_ ] o.a.s.c.Overseer Overseer (id=72985985160511493-127.0.0.1:34651_-n_0000000000) starting
[junit4] 2> 4141430 INFO (OverseerStateUpdate-72985985160511493-127.0.0.1:34651_-n_0000000000) [n:127.0.0.1:34651_ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:34651_
[junit4] 2> 4141430 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [n:127.0.0.1:34651_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34651_
[junit4] 2> 4141432 INFO (zkCallback-21078-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4141444 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [n:127.0.0.1:34651_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 4141444 WARN (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [n:127.0.0.1:34651_ ] 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> 4141599 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [n:127.0.0.1:34651_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4141717 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [n:127.0.0.1:34651_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51
[junit4] 2> 4141763 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [n:127.0.0.1:34651_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51
[junit4] 2> 4141763 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [n:127.0.0.1:34651_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51
[junit4] 2> 4141789 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [n:127.0.0.1:34651_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/control-001/cores
[junit4] 2> 4141827 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4141841 INFO (zkConnectionManagerCallback-21096-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4141841 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4141843 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4141844 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36718/solr ready
[junit4] 2> 4141965 INFO (qtp8419709-106551) [n:127.0.0.1:34651_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:34651_&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4141977 INFO (OverseerThreadFactory-21086-thread-1-processing-n:127.0.0.1:34651_) [n:127.0.0.1:34651_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 4142155 INFO (qtp8419709-106550) [n:127.0.0.1:34651_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 4142155 INFO (qtp8419709-106550) [n:127.0.0.1:34651_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 4142174 INFO (OverseerStateUpdate-72985985160511493-127.0.0.1:34651_-n_0000000000) [n:127.0.0.1:34651_ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:34651",
[junit4] 2> "node_name":"127.0.0.1:34651_",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 4142377 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ x:control_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 4142432 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 4142623 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 4143508 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 4143557 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 4143558 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51
[junit4] 2> 4143558 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 4143592 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@2bd64695
[junit4] 2> 4143611 WARN (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A, b=B}}}
[junit4] 2> 4144002 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 4144002 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 4144004 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4144004 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4144026 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=25, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.37535758942058617]
[junit4] 2> 4144047 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 4144047 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4144048 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 4144048 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1669742562199470080
[junit4] 2> 4144073 INFO (searcherExecutor-21098-thread-1-processing-n:127.0.0.1:34651_ x:control_collection_shard1_replica_n1 c:control_collection s:shard1 r:core_node2) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 4144090 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 4144090 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1
[junit4] 2> 4144121 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 4144121 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 4144121 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:34651/control_collection_shard1_replica_n1/
[junit4] 2> 4144128 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 4144128 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:34651/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 4144128 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/72985985160511493-core_node2-n_0000000000
[junit4] 2> 4144131 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:34651/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 4144250 INFO (zkCallback-21078-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 4144250 INFO (zkCallback-21078-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 4144251 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 4144254 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1877
[junit4] 2> 4144274 INFO (qtp8419709-106551) [n:127.0.0.1:34651_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 4144364 INFO (zkCallback-21078-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 4144364 INFO (zkCallback-21078-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 4144364 INFO (zkCallback-21078-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 4144365 INFO (qtp8419709-106551) [n:127.0.0.1:34651_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:34651_&wt=javabin&version=2} status=0 QTime=2400
[junit4] 2> 4144366 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Waiting to see 1 active replicas in collection: control_collection
[junit4] 2> 4144488 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4144502 INFO (zkConnectionManagerCallback-21107-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4144502 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4144503 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4144504 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36718/solr ready
[junit4] 2> 4144505 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 4144647 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4144674 INFO (OverseerThreadFactory-21086-thread-2-processing-n:127.0.0.1:34651_) [n:127.0.0.1:34651_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 4144674 INFO (OverseerCollectionConfigSetProcessor-72985985160511493-127.0.0.1:34651_-n_0000000000) [n:127.0.0.1:34651_ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 4144888 WARN (OverseerThreadFactory-21086-thread-2-processing-n:127.0.0.1:34651_) [n:127.0.0.1:34651_ ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 4144889 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 4144890 INFO (qtp8419709-106549) [n:127.0.0.1:34651_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&wt=javabin&version=2} status=0 QTime=243
[junit4] 2> 4144900 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 4144900 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 4144900 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 4144900 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 4144901 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 4144901 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 4144901 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=16
[junit4] 2> 4146269 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/shard-1-001 of type NRT for shard2
[junit4] 2> 4146285 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 4146285 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 4146285 INFO (closeThreadPool-21108-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.4+10-LTS
[junit4] 2> 4146286 INFO (closeThreadPool-21108-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4146286 INFO (closeThreadPool-21108-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4146286 INFO (closeThreadPool-21108-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 4146286 INFO (closeThreadPool-21108-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@14c73e71{/,null,AVAILABLE}
[junit4] 2> 4146288 INFO (closeThreadPool-21108-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@43ca7bcd{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:44550}
[junit4] 2> 4146288 INFO (closeThreadPool-21108-thread-1) [ ] o.e.j.s.Server Started @4146327ms
[junit4] 2> 4146288 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/tempDir-001/jetty1, hostPort=44550, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/shard-1-001/cores, replicaType=NRT}
[junit4] 2> 4146288 ERROR (closeThreadPool-21108-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4146288 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4146288 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 4146288 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4146288 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr
[junit4] 2> 4146288 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-06-17T10:43:18.820469Z
[junit4] 2> 4146289 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4146290 INFO (zkConnectionManagerCallback-21110-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4146290 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4146392 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 4146392 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/shard-1-001/solr.xml
[junit4] 2> 4146397 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 4146676 INFO (OverseerCollectionConfigSetProcessor-72985985160511493-127.0.0.1:34651_-n_0000000000) [n:127.0.0.1:34651_ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 4147415 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/shard-2-001 of type NRT for shard1
[junit4] 2> 4147427 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 4147427 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 4147427 INFO (closeThreadPool-21108-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.4+10-LTS
[junit4] 2> 4147540 INFO (closeThreadPool-21108-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4147540 INFO (closeThreadPool-21108-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4147540 INFO (closeThreadPool-21108-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 4147540 INFO (closeThreadPool-21108-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@d7d572f{/,null,AVAILABLE}
[junit4] 2> 4147542 INFO (closeThreadPool-21108-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@16e51477{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:46804}
[junit4] 2> 4147542 INFO (closeThreadPool-21108-thread-2) [ ] o.e.j.s.Server Started @4147581ms
[junit4] 2> 4147542 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/tempDir-001/jetty2, hostPort=46804, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/shard-2-001/cores, replicaType=NRT}
[junit4] 2> 4147542 ERROR (closeThreadPool-21108-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4147542 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4147556 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 4147556 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4147556 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr
[junit4] 2> 4147556 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-06-17T10:43:20.088812Z
[junit4] 2> 4147626 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4147627 INFO (zkConnectionManagerCallback-21115-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4147627 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4147729 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 4147729 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/shard-2-001/solr.xml
[junit4] 2> 4147794 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 4149131 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 4149209 WARN (closeThreadPool-21108-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4142bc51[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4149210 WARN (closeThreadPool-21108-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4142bc51[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4149340 WARN (closeThreadPool-21108-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@51ca8451[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4149340 WARN (closeThreadPool-21108-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@51ca8451[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4149342 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36718/solr
[junit4] 2> 4149380 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4149404 INFO (zkConnectionManagerCallback-21126-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4149404 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4149526 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:44550_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4149528 INFO (zkConnectionManagerCallback-21128-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4149528 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:44550_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4149597 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:44550_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4149609 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:44550_ ] o.a.s.c.ZkController Publish node=127.0.0.1:44550_ as DOWN
[junit4] 2> 4149610 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:44550_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 4149610 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:44550_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44550_
[junit4] 2> 4149622 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:44550_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 4149623 WARN (closeThreadPool-21108-thread-1) [n:127.0.0.1:44550_ ] 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> 4149637 INFO (zkCallback-21078-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4149665 INFO (zkCallback-21127-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4149665 INFO (zkCallback-21106-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4149831 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:44550_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4149960 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:44550_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51
[junit4] 2> 4150031 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:44550_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51
[junit4] 2> 4150031 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:44550_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51
[junit4] 2> 4150033 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:44550_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/shard-1-001/cores
[junit4] 2> 4150152 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:44550_
[junit4] 2> 4150623 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/shard-3-001 of type NRT for shard2
[junit4] 2> 4150626 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 4150626 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 4150626 INFO (closeThreadPool-21108-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.4+10-LTS
[junit4] 2> 4150721 INFO (closeThreadPool-21108-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4150721 INFO (closeThreadPool-21108-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4150721 INFO (closeThreadPool-21108-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 4150722 INFO (closeThreadPool-21108-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@746f71a4{/,null,AVAILABLE}
[junit4] 2> 4150723 INFO (closeThreadPool-21108-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1c71d51b{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:45734}
[junit4] 2> 4150723 INFO (closeThreadPool-21108-thread-1) [ ] o.e.j.s.Server Started @4150762ms
[junit4] 2> 4150723 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/tempDir-001/jetty3, hostPort=45734, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/shard-3-001/cores}
[junit4] 2> 4150723 ERROR (closeThreadPool-21108-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4150723 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4150741 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 4150742 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4150742 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr
[junit4] 2> 4150742 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-06-17T10:43:23.274073Z
[junit4] 2> 4150840 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4150868 INFO (zkConnectionManagerCallback-21138-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4150869 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4150970 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 4150970 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/shard-3-001/solr.xml
[junit4] 2> 4150975 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 4151886 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 4151985 WARN (closeThreadPool-21108-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3d8b577c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4151985 WARN (closeThreadPool-21108-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3d8b577c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4152090 WARN (closeThreadPool-21108-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@193d2217[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4152090 WARN (closeThreadPool-21108-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@193d2217[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4152092 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36718/solr
[junit4] 2> 4152151 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4152167 INFO (zkConnectionManagerCallback-21149-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4152167 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4152338 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:46804_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4152339 INFO (zkConnectionManagerCallback-21151-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4152339 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:46804_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4152353 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:46804_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 4152357 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:46804_ ] o.a.s.c.ZkController Publish node=127.0.0.1:46804_ as DOWN
[junit4] 2> 4152358 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:46804_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 4152358 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:46804_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46804_
[junit4] 2> 4152359 INFO (zkCallback-21106-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4152359 INFO (zkCallback-21127-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4152359 INFO (zkCallback-21078-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4152361 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:46804_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 4152362 WARN (closeThreadPool-21108-thread-2) [n:127.0.0.1:46804_ ] 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> 4152476 INFO (zkCallback-21150-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4152492 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:46804_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4152759 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:46804_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51
[junit4] 2> 4152848 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:46804_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51
[junit4] 2> 4152848 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:46804_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51
[junit4] 2> 4152867 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:46804_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/shard-2-001/cores
[junit4] 2> 4153014 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:46804_
[junit4] 2> 4153620 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/shard-4-001 of type NRT for shard1
[junit4] 2> 4153623 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 4153623 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 4153623 INFO (closeThreadPool-21108-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.4+10-LTS
[junit4] 2> 4153715 INFO (closeThreadPool-21108-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4153715 INFO (closeThreadPool-21108-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4153715 INFO (closeThreadPool-21108-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 4153715 INFO (closeThreadPool-21108-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2085bdda{/,null,AVAILABLE}
[junit4] 2> 4153717 INFO (closeThreadPool-21108-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@45b15b32{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:36176}
[junit4] 2> 4153717 INFO (closeThreadPool-21108-thread-2) [ ] o.e.j.s.Server Started @4153756ms
[junit4] 2> 4153717 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/tempDir-001/jetty4, hostPort=36176, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/shard-4-001/cores}
[junit4] 2> 4153717 ERROR (closeThreadPool-21108-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4153717 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4153731 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 4153731 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4153731 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr
[junit4] 2> 4153731 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-06-17T10:43:26.263497Z
[junit4] 2> 4153768 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4153769 INFO (zkConnectionManagerCallback-21161-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4153769 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4153871 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 4153871 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/shard-4-001/solr.xml
[junit4] 2> 4153876 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 4156376 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 4156474 WARN (closeThreadPool-21108-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@35552f6c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4156474 WARN (closeThreadPool-21108-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@35552f6c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4156519 WARN (closeThreadPool-21108-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@77444d05[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4156519 WARN (closeThreadPool-21108-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@77444d05[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4156521 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36718/solr
[junit4] 2> 4156530 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4156532 INFO (zkConnectionManagerCallback-21172-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4156532 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4156668 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:36176_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4156705 INFO (zkConnectionManagerCallback-21174-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4156705 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:36176_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4156711 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:36176_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 4156715 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:36176_ ] o.a.s.c.ZkController Publish node=127.0.0.1:36176_ as DOWN
[junit4] 2> 4156716 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:36176_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 4156716 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:36176_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36176_
[junit4] 2> 4156718 INFO (zkCallback-21127-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4156718 INFO (zkCallback-21150-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4156718 INFO (zkCallback-21106-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4156720 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:36176_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 4156720 WARN (closeThreadPool-21108-thread-2) [n:127.0.0.1:36176_ ] 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> 4156725 INFO (zkCallback-21078-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4156843 INFO (zkCallback-21173-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4156969 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:36176_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4157082 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 4157103 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:36176_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51
[junit4] 2> 4157157 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:36176_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51
[junit4] 2> 4157157 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:36176_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51
[junit4] 2> 4157159 INFO (closeThreadPool-21108-thread-2) [n:127.0.0.1:36176_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/shard-4-001/cores
[junit4] 2> 4157184 WARN (closeThreadPool-21108-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4a8e1408[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4157185 WARN (closeThreadPool-21108-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4a8e1408[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4157248 WARN (closeThreadPool-21108-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1a97a9c3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4157248 WARN (closeThreadPool-21108-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1a97a9c3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4157250 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36718/solr
[junit4] 2> 4157251 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4157252 INFO (zkConnectionManagerCallback-21190-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4157252 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4157319 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:36176_
[junit4] 2> 4157464 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:45734_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4157845 INFO (zkConnectionManagerCallback-21192-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4157845 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:45734_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4157852 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:45734_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 4157856 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:45734_ ] o.a.s.c.ZkController Publish node=127.0.0.1:45734_ as DOWN
[junit4] 2> 4157857 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:45734_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 4157857 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:45734_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45734_
[junit4] 2> 4157859 INFO (zkCallback-21106-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 4157859 INFO (zkCallback-21078-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 4157859 INFO (zkCallback-21127-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 4157859 INFO (zkCallback-21150-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 4157874 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:45734_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 4157874 WARN (closeThreadPool-21108-thread-1) [n:127.0.0.1:45734_ ] 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> 4157899 INFO (zkCallback-21173-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 4157923 INFO (zkCallback-21191-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 4158024 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:45734_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4158135 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:45734_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51
[junit4] 2> 4158172 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:45734_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51
[junit4] 2> 4158172 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:45734_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51
[junit4] 2> 4158191 INFO (closeThreadPool-21108-thread-1) [n:127.0.0.1:45734_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/shard-3-001/cores
[junit4] 2> 4158236 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:45734_
[junit4] 2> 4158411 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 5 in directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/shard-5-001 of type NRT for shard2
[junit4] 2> 4158414 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 4158414 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 4158414 INFO (closeThreadPool-21108-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.4+10-LTS
[junit4] 2> 4158415 INFO (closeThreadPool-21108-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4158415 INFO (closeThreadPool-21108-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4158415 INFO (closeThreadPool-21108-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 4158416 INFO (closeThreadPool-21108-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@34f4dba9{/,null,AVAILABLE}
[junit4] 2> 4158417 INFO (closeThreadPool-21108-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@385ee2f7{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:42690}
[junit4] 2> 4158417 INFO (closeThreadPool-21108-thread-1) [ ] o.e.j.s.Server Started @4158456ms
[junit4] 2> 4158417 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/tempDir-001/jetty5, hostPort=42690, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/shard-5-001/cores}
[junit4] 2> 4158417 ERROR (closeThreadPool-21108-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4158417 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4158417 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 4158418 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4158418 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr
[junit4] 2> 4158418 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-06-17T10:43:30.950074Z
[junit4] 2> 4158419 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4158420 INFO (zkConnectionManagerCallback-21202-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4158420 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4158527 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 4158527 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/shard-5-001/solr.xml
[junit4] 2> 4158532 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@46574f51, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 4160297 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 4160298 WARN (closeThreadPool-21108-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@546bf4a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4160298 WARN (closeThreadPool-21108-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@546bf4a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4160301 WARN (closeThreadPool-21108-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3ba9c110[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4160301 WARN (closeThreadPool-21108-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3ba9c110[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4160303 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36718/solr
[junit4] 2> 4160426 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4160427 INFO (zkConnectionManagerCallback-21213-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4160427 INFO (closeThreadPool-21108-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4160513 INFO (TEST-RollingRestartTest.test-seed#[728B5D8C6DC94ED7]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 6 in directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/shard-6-001 of type NRT for shard1
[junit4] 2> 4160516 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 4160516 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 4160516 INFO (closeThreadPool-21108-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.4+10-LTS
[junit4] 2> 4160517 INFO (closeThreadPool-21108-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4160517 INFO (closeThreadPool-21108-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4160517 INFO (closeThreadPool-21108-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 4160517 INFO (closeThreadPool-21108-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@743a2789{/,null,AVAILABLE}
[junit4] 2> 4160518 INFO (closeThreadPool-21108-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@12d28ed7{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:46805}
[junit4] 2> 4160518 INFO (closeThreadPool-21108-thread-2) [ ] o.e.j.s.Server Started @4160557ms
[junit4] 2> 4160518 INFO (closeThreadPool-21108-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001/tempDir-001/jetty6, hostPort=46805, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Luc
[...truncated too long message...]
SolrClient.java:370)
[junit4] > at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:298)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1155)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:916)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:850)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:210)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:227)
[junit4] > at org.apache.solr.cloud.RollingRestartTest.restartWithRolesTest(RollingRestartTest.java:74)
[junit4] > at org.apache.solr.cloud.RollingRestartTest.test(RollingRestartTest.java:53)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:566)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] > Caused by: java.net.SocketTimeoutException: Read timed out
[junit4] > at java.base/java.net.SocketInputStream.socketRead0(Native Method)
[junit4] > at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
[junit4] > at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
[junit4] > at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
[junit4] > at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:448)
[junit4] > at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68)
[junit4] > at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1104)
[junit4] > at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:823)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
[junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
[junit4] > at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
[junit4] > at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
[junit4] > at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
[junit4] > at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186)
[junit4] > at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
[junit4] > at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
[junit4] > at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:571)
[junit4] > ... 52 more
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_728B5D8C6DC94ED7-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene86): {}, docValues:{}, maxPointsInLeafNode=1969, maxMBSortInHeap=7.2495341267373306, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@6402a7aa), locale=es-NI, timezone=Etc/GMT+4
[junit4] 2> NOTE: Linux 4.4.0-170-generic amd64/Oracle Corporation 11.0.4 (64-bit)/cpus=8,threads=1,free=305780400,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [BasicAuthIntegrationTest, TestRandomFaceting, HdfsAutoAddReplicasIntegrationTest, ZkDynamicConfigTest, TimeRoutedAliasUpdateProcessorTest, CertAuthPluginTest, TestCoreContainer, TestSegmentSorting, TestLegacyTerms, ZkNodePropsTest, TestSolrTestCaseJ4, ChaosMonkeyNothingIsSafeWithPullReplicasTest, DistributedQueryElevationComponentTest, TestBinaryField, TestStressLucene, TestCustomStream, TestRawResponseWriter, SplitByPrefixTest, TestCryptoKeys, HdfsWriteToMultipleCollectionsTest, TestUnifiedSolrHighlighter, QueryResultKeyTest, TestIndexSearcher, TestCustomDocTransformer, SSLMigrationTest, HighlighterTest, SmileWriterTest, CdcrVersionReplicationTest, TestSchemaField, TestFileDictionaryLookup, AnalyticsQueryTest, TestRSAKeyPair, TestSolrQueryResponse, TestFoldingMultitermQuery, HdfsCollectionsAPIDistributedZkTest, DaemonStreamApiTest, TestRequestStatusCollectionAPI, AddSchemaFieldsUpdateProcessorFactoryTest, ImplicitSnitchTest, ShowFileRequestHandlerTest, TestCursorMarkWithoutUniqueKey, TestQueryTypes, TestFieldCache, ReturnFieldsTest, RecoveryZkTest, TestManagedSchemaThreadSafety, MoreLikeThisComponentTest, TestBlobHandler, PeerSyncWithLeaderTest, TestConfigSetsAPI, ConfigureRecoveryStrategyTest, TestSimNodeLostTrigger, BigEndianAscendingWordDeserializerTest, TestSimGenericDistributedQueue, SpellCheckCollatorWithCollapseTest, UninvertDocValuesMergePolicyTest, TriLevelCompositeIdRoutingTest, OverseerStatusTest, ExitableDirectoryReaderTest, TestNumericRangeQuery64, MissingSegmentRecoveryTest, SolrSlf4jReporterTest, TestTrackingShardHandlerFactory, LeaderFailureAfterFreshStartTest, HttpSolrCallGetCoreTest, GraphQueryTest, PeerSyncWithBufferUpdatesTest, HdfsChaosMonkeySafeLeaderTest, TestSkipOverseerOperations, TestCollectionAPIs, PropertiesRequestHandlerTest, DeleteShardTest, DistributedVersionInfoTest, ConcurrentCreateRoutedAliasTest, TestDistribIDF, SolrCLIZkUtilsTest, SimpleFacetsTest, PingRequestHandlerTest, SystemLogListenerTest, TestTolerantUpdateProcessorCloud, DistributedMLTComponentTest, TestWaitForStateWithJettyShutdowns, TestDistributedMap, TestTermsQParserPlugin, DistributedFacetPivotSmallAdvancedTest, BJQFilterAccessibleTest, TaggerTest, ExternalFileFieldSortTest, TestDynamicLoadingUrl, IgnoreLargeDocumentProcessorFactoryTest, BlockDirectoryTest, MetricTriggerTest, DynamicMapsTest, SolrIndexSplitterTest, HdfsRecoveryZkTest, TestSortByMinMaxFunction, TestExpandComponent, TestSchemaNameResource, TestMultiWordSynonyms, ResourceLoaderTest, TestLazyCores, MBeansHandlerTest, ClusterStateMockUtilTest, ScheduledTriggerTest, SolrInfoBeanTest, TestStreamBody, TestConfigSetImmutable, TestCloudJSONFacetSKG, TestScoreJoinQPNoScore, TestObjectReleaseTracker, LeaderElectionIntegrationTest, DOMUtilTest, TestDynamicFieldCollectionResource, TestSimTriggerIntegration, TestSolrCoreParser, UpdateLogTest, FuzzySearchTest, XmlUpdateRequestHandlerTest, TestManagedStopFilterFactory, TestCloudPivotFacet, TestFieldCacheSortRandom, HdfsThreadLeakTest, TestShardHandlerFactory, TestLegacyFieldCache, ForceLeaderWithTlogReplicasTest, DeleteLastCustomShardedReplicaTest, SolrMetricManagerTest, BasicDistributedZk2Test, TestSortableTextField, TestSchemaVersionResource, CollectionTooManyReplicasTest, OverriddenZkACLAndCredentialsProvidersTest, DistribDocExpirationUpdateProcessorTest, FieldAnalysisRequestHandlerTest, HttpPartitionTest, TestPullReplica, TestTestInjection, TestFieldResource, TestFieldSortValues, TestSolrQueryParser, TestSolrCloudWithSecureImpersonation, TestSimClusterStateProvider, BadCopyFieldTest, DistributedTermsComponentTest, TestDocBasedVersionConstraints, TestUninvertingReader, TestCoreBackup, TestHdfsCloudBackupRestore, BasicFunctionalityTest, ConvertedLegacyTest, DisMaxRequestHandlerTest, DistributedIntervalFacetingTest, EchoParamsTest, MinimalSchemaTest, OutputWriterTest, SampleTest, TestDistributedSearch, TestDocumentBuilder, TestHighlightDedupGrouping, TestJoin, TestRandomDVFaceting, TestTrie, PathHierarchyTokenizerFactoryTest, ProtectedTermFilterFactoryTest, TestDeprecatedFilters, TestLuceneMatchVersion, TestWordDelimiterFilterFactory, TokenizerChainTest, TestEmbeddedSolrServerAdminHandler, TestEmbeddedSolrServerConstructors, TestEmbeddedSolrServerSchemaAPI, TestJettySolrRunner, ConnectionReuseTest, AddReplicaTest, AliasIntegrationTest, AssignBackwardCompatibilityTest, ChaosMonkeyNothingIsSafeTest, CleanupOldIndexTest, CollectionPropsTest, ConnectionManagerTest, CreateCollectionCleanupTest, CreateRoutedAliasTest, DistributedQueueTest, ForceLeaderTest, HttpPartitionOnCommitTest, MetricsHistoryIntegrationTest, NestedShardedAtomicUpdateTest, OverseerRolesTest, PeerSyncReplicationTest, RollingRestartTest]
[junit4] Completed [496/910 (1!)] on J1 in 223.29s, 1 test, 1 error <<< FAILURES!
[...truncated 53415 lines...]