You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/08/14 01:42:15 UTC
[JENKINS-EA] Lucene-Solr-master-Linux (32bit/jdk-9-ea+181) - Build
# 20313 - Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/20313/
Java: 32bit/jdk-9-ea+181 -server -XX:+UseConcMarkSweepGC --illegal-access=deny
1 tests failed.
FAILED: org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testParallelExecutorStream
Error Message:
Error from server at https://127.0.0.1:41873/solr/workQueue_shard2_replica_n2: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 404 </title> </head> <body> <h2>HTTP ERROR: 404</h2> <p>Problem accessing /solr/workQueue_shard2_replica_n2/update. Reason: <pre> Can not find: /solr/workQueue_shard2_replica_n2/update</pre></p> <hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.14.v20161028</a><hr/> </body> </html>
Stack Trace:
org.apache.solr.client.solrj.impl.CloudSolrClient$RouteException: Error from server at https://127.0.0.1:41873/solr/workQueue_shard2_replica_n2: Expected mime type application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
<title>Error 404 </title>
</head>
<body>
<h2>HTTP ERROR: 404</h2>
<p>Problem accessing /solr/workQueue_shard2_replica_n2/update. Reason:
<pre> Can not find: /solr/workQueue_shard2_replica_n2/update</pre></p>
<hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.14.v20161028</a><hr/>
</body>
</html>
at __randomizedtesting.SeedInfo.seed([8AA48C343550DCED:37B3F92D0C7CE1B0]:0)
at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:539)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:993)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:862)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:793)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178)
at org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:233)
at org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testParallelExecutorStream(StreamExpressionTest.java:6884)
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:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
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:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:41873/solr/workQueue_shard2_replica_n2: Expected mime type application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
<title>Error 404 </title>
</head>
<body>
<h2>HTTP ERROR: 404</h2>
<p>Problem accessing /solr/workQueue_shard2_replica_n2/update. Reason:
<pre> Can not find: /solr/workQueue_shard2_replica_n2/update</pre></p>
<hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.14.v20161028</a><hr/>
</body>
</html>
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:591)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
at org.apache.solr.client.solrj.impl.CloudSolrClient.lambda$directUpdate$0(CloudSolrClient.java:516)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
... 1 more
Build Log:
[...truncated 1700 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp/junit4-J1-20170814_003120_5344565689228566599319.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp/junit4-J2-20170814_003120_5343543356571238286457.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 9 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp/junit4-J0-20170814_003120_5336543299210567309122.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 295 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/test-framework/test/temp/junit4-J2-20170814_004019_93110833348889845142864.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/test-framework/test/temp/junit4-J0-20170814_004019_9312472566198947242163.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/test-framework/test/temp/junit4-J1-20170814_004019_931912153994375169468.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 1033 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/common/test/temp/junit4-J1-20170814_004201_9299865836328239595813.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/common/test/temp/junit4-J0-20170814_004201_9296714604909884920317.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/common/test/temp/junit4-J2-20170814_004201_92913570720885921417822.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 218 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/icu/test/temp/junit4-J0-20170814_004358_6719279370560174449423.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/icu/test/temp/junit4-J2-20170814_004358_67116876066577015550530.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/icu/test/temp/junit4-J1-20170814_004358_6715992636760348840802.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 240 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/kuromoji/test/temp/junit4-J2-20170814_004412_04413721466091561029412.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/kuromoji/test/temp/junit4-J0-20170814_004412_0443149968747503743881.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/kuromoji/test/temp/junit4-J1-20170814_004412_0444786565112040655643.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 146 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/morfologik/test/temp/junit4-J1-20170814_004436_4616955793043428233620.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/morfologik/test/temp/junit4-J2-20170814_004436_4618384339561803729954.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/morfologik/test/temp/junit4-J0-20170814_004436_4612132113226349398604.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 161 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/phonetic/test/temp/junit4-J1-20170814_004440_14310122748939197883556.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/phonetic/test/temp/junit4-J2-20170814_004440_139916946955379138117.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/phonetic/test/temp/junit4-J0-20170814_004440_13816992389931722122698.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 145 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/smartcn/test/temp/junit4-J1-20170814_004450_43914960521070366785726.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/smartcn/test/temp/junit4-J0-20170814_004450_43914389766262461081508.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 149 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/stempel/test/temp/junit4-J2-20170814_004501_00312061530997871647135.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/stempel/test/temp/junit4-J1-20170814_004501_0037767178051486142944.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/stempel/test/temp/junit4-J0-20170814_004501_00317392101682425578695.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 161 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/uima/test/temp/junit4-J0-20170814_004503_39715301434039571779143.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 145 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/backward-codecs/test/temp/junit4-J0-20170814_004511_43014270240217769598223.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/backward-codecs/test/temp/junit4-J1-20170814_004511_43212865030990959729039.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 25 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/backward-codecs/test/temp/junit4-J2-20170814_004511_43310268772459281698084.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 1209 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/benchmark/test/temp/junit4-J1-20170814_004522_67611148348778484508430.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/benchmark/test/temp/junit4-J2-20170814_004522_6763126119577282969827.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/benchmark/test/temp/junit4-J0-20170814_004522_67610356739664115300141.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 223 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/classification/test/temp/junit4-J0-20170814_004530_00010350392316653218338.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/classification/test/temp/junit4-J2-20170814_004530_0014282959822194621360.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/classification/test/temp/junit4-J1-20170814_004530_00014252567736638537724.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 245 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/codecs/test/temp/junit4-J0-20170814_004543_83016086937156298689300.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 9 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/codecs/test/temp/junit4-J1-20170814_004543_830638597901474576023.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/codecs/test/temp/junit4-J2-20170814_004543_8309698398355930654263.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 229 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/demo/test/temp/junit4-J0-20170814_004817_49616789894567432357439.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/demo/test/temp/junit4-J2-20170814_004817_4965195918151604959528.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/demo/test/temp/junit4-J1-20170814_004817_4961745649978381717930.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 162 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/expressions/test/temp/junit4-J1-20170814_004819_9953665587122737480381.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/expressions/test/temp/junit4-J2-20170814_004819_9954430634225757484718.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 6 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/expressions/test/temp/junit4-J0-20170814_004819_9958849542239647842509.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 213 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/facet/test/temp/junit4-J0-20170814_004826_5259676987838707350487.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/facet/test/temp/junit4-J2-20170814_004826_52515672759671939473138.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/facet/test/temp/junit4-J1-20170814_004826_5257536760224844037686.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 171 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/grouping/test/temp/junit4-J0-20170814_004858_6656908076428429715841.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/grouping/test/temp/junit4-J2-20170814_004858_66512857717120008800946.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/grouping/test/temp/junit4-J1-20170814_004858_66514397372910150051385.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 238 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/highlighter/test/temp/junit4-J2-20170814_004919_2964596713393367441697.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/highlighter/test/temp/junit4-J1-20170814_004919_29514785866312410127887.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/highlighter/test/temp/junit4-J0-20170814_004919_2943836263812620149857.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 152 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/join/test/temp/junit4-J2-20170814_004937_2494553693845868801481.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/join/test/temp/junit4-J0-20170814_004937_2492549088313756655974.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 6 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/join/test/temp/junit4-J1-20170814_004937_24911599201756630911464.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 141 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/memory/test/temp/junit4-J0-20170814_004957_8891426750696112477264.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/memory/test/temp/junit4-J1-20170814_004957_8898490144822008123677.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 166 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/misc/test/temp/junit4-J1-20170814_005008_5597140838797977651256.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/misc/test/temp/junit4-J2-20170814_005008_56012179640883027751346.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 9 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/misc/test/temp/junit4-J0-20170814_005008_55914829578557539917349.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 304 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/queries/test/temp/junit4-J1-20170814_005026_1107803905874143024686.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/queries/test/temp/junit4-J0-20170814_005026_11013379514065045927771.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/queries/test/temp/junit4-J2-20170814_005026_11014269440920616011983.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 219 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/queryparser/test/temp/junit4-J0-20170814_005032_83712262173097567647958.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/queryparser/test/temp/junit4-J2-20170814_005032_837306291770451474345.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/queryparser/test/temp/junit4-J1-20170814_005032_837430449558064603979.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 199 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/replicator/test/temp/junit4-J1-20170814_005040_17716438336070801494562.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/replicator/test/temp/junit4-J2-20170814_005040_1925543972104891170103.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/replicator/test/temp/junit4-J0-20170814_005040_1771833382257259162578.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 197 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/sandbox/test/temp/junit4-J1-20170814_005053_578974749422233726895.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/sandbox/test/temp/junit4-J2-20170814_005053_57910051912292243981146.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 7 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/sandbox/test/temp/junit4-J0-20170814_005053_57816129577486442331264.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 207 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/spatial-extras/test/temp/junit4-J0-20170814_005132_97112097298998480410382.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/spatial-extras/test/temp/junit4-J2-20170814_005132_9714522006546863949768.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/spatial-extras/test/temp/junit4-J1-20170814_005132_97114273646395160817491.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 140 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/spatial/test/temp/junit4-J0-20170814_005144_39915925279764268300373.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 163 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/spatial3d/test/temp/junit4-J2-20170814_005146_90412319204795309091843.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 7 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/spatial3d/test/temp/junit4-J0-20170814_005146_90311969191144774205598.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/spatial3d/test/temp/junit4-J1-20170814_005146_9042024375846836121654.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 233 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/suggest/test/temp/junit4-J2-20170814_005332_53917765840529395737898.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 12 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/suggest/test/temp/junit4-J1-20170814_005332_5398150288083350408852.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/suggest/test/temp/junit4-J0-20170814_005332_5399711700418822609521.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3808 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/temp/junit4-J2-20170814_005438_07011134282333382959492.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 4 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/temp/junit4-J0-20170814_005438_0706086135873864791908.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/temp/junit4-J1-20170814_005438_0704562040508169918524.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 726 lines...]
[junit4] Suite: org.apache.solr.client.solrj.io.stream.StreamExpressionTest
[junit4] 2> 11291 INFO (SUITE-StreamExpressionTest-seed#[8AA48C343550DCED]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_8AA48C343550DCED-001/init-core-data-001
[junit4] 2> 11291 WARN (SUITE-StreamExpressionTest-seed#[8AA48C343550DCED]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=112 numCloses=112
[junit4] 2> 11291 INFO (SUITE-StreamExpressionTest-seed#[8AA48C343550DCED]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 11292 INFO (SUITE-StreamExpressionTest-seed#[8AA48C343550DCED]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 11303 INFO (SUITE-StreamExpressionTest-seed#[8AA48C343550DCED]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_8AA48C343550DCED-001/tempDir-001
[junit4] 2> 11306 INFO (SUITE-StreamExpressionTest-seed#[8AA48C343550DCED]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 11309 INFO (Thread-12) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 11310 INFO (Thread-12) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 11409 INFO (SUITE-StreamExpressionTest-seed#[8AA48C343550DCED]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:40471
[junit4] 2> 11449 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running
[junit4] 2> 11477 ERROR (Thread-12) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 13406 INFO (jetty-launcher-3-thread-3) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 13407 INFO (jetty-launcher-3-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 13407 INFO (jetty-launcher-3-thread-2) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 13458 INFO (jetty-launcher-3-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@13c3924{/solr,null,AVAILABLE}
[junit4] 2> 13466 INFO (jetty-launcher-3-thread-4) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 13560 INFO (jetty-launcher-3-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1cdf517{/solr,null,AVAILABLE}
[junit4] 2> 13561 INFO (jetty-launcher-3-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1893468{SSL,[ssl, http/1.1]}{127.0.0.1:39107}
[junit4] 2> 13561 INFO (jetty-launcher-3-thread-3) [ ] o.e.j.s.Server Started @14724ms
[junit4] 2> 13561 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39107}
[junit4] 2> 13562 ERROR (jetty-launcher-3-thread-3) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 13562 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 13562 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 13562 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 13562 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-14T01:33:24.089308Z
[junit4] 2> 13650 INFO (jetty-launcher-3-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1121813{/solr,null,AVAILABLE}
[junit4] 2> 13650 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 13673 INFO (jetty-launcher-3-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@605ccb{/solr,null,AVAILABLE}
[junit4] 2> 13678 INFO (jetty-launcher-3-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@209251{SSL,[ssl, http/1.1]}{127.0.0.1:41873}
[junit4] 2> 13678 INFO (jetty-launcher-3-thread-4) [ ] o.e.j.s.Server Started @14841ms
[junit4] 2> 13678 INFO (jetty-launcher-3-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41873}
[junit4] 2> 13678 ERROR (jetty-launcher-3-thread-4) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 13679 INFO (jetty-launcher-3-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 13679 INFO (jetty-launcher-3-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 13679 INFO (jetty-launcher-3-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 13679 INFO (jetty-launcher-3-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-14T01:33:24.206227Z
[junit4] 2> 13690 INFO (jetty-launcher-3-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@e5bcdc{SSL,[ssl, http/1.1]}{127.0.0.1:39493}
[junit4] 2> 13690 INFO (jetty-launcher-3-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1cc3270{SSL,[ssl, http/1.1]}{127.0.0.1:38617}
[junit4] 2> 13690 INFO (jetty-launcher-3-thread-2) [ ] o.e.j.s.Server Started @14852ms
[junit4] 2> 13690 INFO (jetty-launcher-3-thread-1) [ ] o.e.j.s.Server Started @14852ms
[junit4] 2> 13690 INFO (jetty-launcher-3-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39493}
[junit4] 2> 13690 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38617}
[junit4] 2> 13690 ERROR (jetty-launcher-3-thread-2) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 13690 ERROR (jetty-launcher-3-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 13690 INFO (jetty-launcher-3-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 13690 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 13691 INFO (jetty-launcher-3-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 13691 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 13691 INFO (jetty-launcher-3-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 13691 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 13691 INFO (jetty-launcher-3-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-14T01:33:24.218069Z
[junit4] 2> 13691 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-14T01:33:24.218085Z
[junit4] 2> 13762 INFO (jetty-launcher-3-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 13766 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 13785 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 13785 INFO (jetty-launcher-3-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 13822 INFO (jetty-launcher-3-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40471/solr
[junit4] 2> 13869 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40471/solr
[junit4] 2> 13891 INFO (jetty-launcher-3-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40471/solr
[junit4] 2> 13974 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40471/solr
[junit4] 2> 14068 WARN (jetty-launcher-3-thread-4) [ ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 14068 WARN (jetty-launcher-3-thread-1) [ ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 14069 WARN (jetty-launcher-3-thread-3) [ ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 14070 WARN (jetty-launcher-3-thread-2) [ ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 14091 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 14092 INFO (jetty-launcher-3-thread-4) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 14093 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 14093 INFO (jetty-launcher-3-thread-2) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 14095 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38617_solr
[junit4] 2> 14097 INFO (jetty-launcher-3-thread-4) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41873_solr
[junit4] 2> 14097 INFO (jetty-launcher-3-thread-2) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39493_solr
[junit4] 2> 14097 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39107_solr
[junit4] 2> 14099 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.c.Overseer Overseer (id=98479269602066444-127.0.0.1:38617_solr-n_0000000000) starting
[junit4] 2> 14100 INFO (zkCallback-21-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 14100 INFO (zkCallback-24-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 14101 INFO (zkCallback-22-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 14100 INFO (zkCallback-25-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 14105 INFO (zkCallback-21-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
[junit4] 2> 14106 INFO (zkCallback-25-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
[junit4] 2> 14106 INFO (zkCallback-24-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
[junit4] 2> 14106 INFO (zkCallback-22-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
[junit4] 2> 14183 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38617_solr
[junit4] 2> 14188 INFO (zkCallback-21-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 14188 INFO (zkCallback-25-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 14190 INFO (zkCallback-24-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 14190 INFO (zkCallback-22-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 14409 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38617.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e2c579
[junit4] 2> 14415 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39107.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e2c579
[junit4] 2> 14415 INFO (jetty-launcher-3-thread-4) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41873.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e2c579
[junit4] 2> 14420 INFO (jetty-launcher-3-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39493.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e2c579
[junit4] 2> 14431 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39107.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e2c579
[junit4] 2> 14431 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39107.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e2c579
[junit4] 2> 14435 INFO (jetty-launcher-3-thread-4) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41873.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e2c579
[junit4] 2> 14435 INFO (jetty-launcher-3-thread-4) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41873.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e2c579
[junit4] 2> 14437 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38617.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e2c579
[junit4] 2> 14437 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38617.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e2c579
[junit4] 2> 14447 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_8AA48C343550DCED-001/tempDir-001/node3/.
[junit4] 2> 14450 INFO (jetty-launcher-3-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39493.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e2c579
[junit4] 2> 14450 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_8AA48C343550DCED-001/tempDir-001/node1/.
[junit4] 2> 14450 INFO (jetty-launcher-3-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39493.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e2c579
[junit4] 2> 14452 INFO (jetty-launcher-3-thread-4) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_8AA48C343550DCED-001/tempDir-001/node4/.
[junit4] 2> 14453 INFO (jetty-launcher-3-thread-2) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_8AA48C343550DCED-001/tempDir-001/node2/.
[junit4] 2> 14672 INFO (SUITE-StreamExpressionTest-seed#[8AA48C343550DCED]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 14673 INFO (SUITE-StreamExpressionTest-seed#[8AA48C343550DCED]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:40471/solr ready
[junit4] 2> 14834 INFO (qtp6035350-101) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=collection1_collection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 14845 INFO (OverseerThreadFactory-32-thread-1) [ ] o.a.s.c.CreateCollectionCmd Create collection collection1_collection
[junit4] 2> 14974 INFO (OverseerStateUpdate-98479269602066444-127.0.0.1:38617_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:41873/solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 14987 INFO (OverseerStateUpdate-98479269602066444-127.0.0.1:38617_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1_collection",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1_collection_shard2_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:39493/solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 15282 INFO (qtp9876881-105) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection1_collection_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 15283 INFO (qtp9876881-105) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 15290 INFO (qtp14679347-114) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_collection_shard2_replica_n3&action=CREATE&numShards=2&collection=collection1_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 15291 INFO (qtp14679347-114) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 15404 INFO (zkCallback-22-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 15404 INFO (zkCallback-24-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 15404 INFO (zkCallback-24-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 15405 INFO (zkCallback-22-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 16340 INFO (qtp14679347-114) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 16392 INFO (qtp9876881-105) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 16419 INFO (qtp14679347-114) [ ] o.a.s.s.IndexSchema [collection1_collection_shard2_replica_n3] Schema name=test
[junit4] 2> 16422 WARN (qtp14679347-114) [ ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieIntField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 16424 WARN (qtp14679347-114) [ ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieFloatField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 16438 INFO (qtp9876881-105) [ ] o.a.s.s.IndexSchema [collection1_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 16441 WARN (qtp9876881-105) [ ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieLongField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 16443 WARN (qtp9876881-105) [ ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieDoubleField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 16443 WARN (qtp14679347-114) [ ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieLongField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 16470 WARN (qtp14679347-114) [ ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieDateField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 16679 WARN (qtp9876881-105) [ ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.GeoHashField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 16682 WARN (qtp9876881-105) [ ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.LatLonType]. Please consult documentation how to replace it accordingly.
[junit4] 2> 16701 WARN (qtp9876881-105) [ ] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 16701 WARN (qtp9876881-105) [ ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 16702 WARN (qtp14679347-114) [ ] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 16702 WARN (qtp14679347-114) [ ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 16702 INFO (qtp9876881-105) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 16702 INFO (qtp14679347-114) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 16703 INFO (qtp9876881-105) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_collection_shard1_replica_n1' using configuration from collection collection1_collection, trusted=true
[junit4] 2> 16704 INFO (qtp9876881-105) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41873.solr.core.collection1_collection.shard1.replica_n1' (registry 'solr.core.collection1_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e2c579
[junit4] 2> 16704 INFO (qtp9876881-105) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 16705 INFO (qtp9876881-105) [ ] o.a.s.c.SolrCore [[collection1_collection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_8AA48C343550DCED-001/tempDir-001/node4/collection1_collection_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_8AA48C343550DCED-001/tempDir-001/node4/./collection1_collection_shard1_replica_n1/data/]
[junit4] 2> 16708 INFO (qtp14679347-114) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_collection_shard2_replica_n3' using configuration from collection collection1_collection, trusted=true
[junit4] 2> 16708 INFO (qtp14679347-114) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39493.solr.core.collection1_collection.shard2.replica_n3' (registry 'solr.core.collection1_collection.shard2.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e2c579
[junit4] 2> 16709 INFO (qtp14679347-114) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 16709 INFO (qtp14679347-114) [ ] o.a.s.c.SolrCore [[collection1_collection_shard2_replica_n3] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_8AA48C343550DCED-001/tempDir-001/node2/collection1_collection_shard2_replica_n3], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_8AA48C343550DCED-001/tempDir-001/node2/./collection1_collection_shard2_replica_n3/data/]
[junit4] 2> 16909 INFO (qtp14679347-114) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 16922 INFO (qtp14679347-114) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 16924 INFO (qtp14679347-114) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 16924 INFO (qtp14679347-114) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 16929 INFO (qtp14679347-114) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7b39a0[collection1_collection_shard2_replica_n3] main]
[junit4] 2> 16949 INFO (qtp9876881-105) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 16949 INFO (qtp9876881-105) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 16952 INFO (qtp9876881-105) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 16952 INFO (qtp9876881-105) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 16940 INFO (qtp14679347-114) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 16954 INFO (qtp14679347-114) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 16957 INFO (qtp9876881-105) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@9a4cbf[collection1_collection_shard1_replica_n1] main]
[junit4] 2> 16962 INFO (qtp14679347-114) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 16968 INFO (searcherExecutor-42-thread-1) [ ] o.a.s.c.SolrCore [collection1_collection_shard2_replica_n3] Registered new searcher Searcher@7b39a0[collection1_collection_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 16968 INFO (qtp9876881-105) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 16968 INFO (qtp9876881-105) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 16969 INFO (qtp9876881-105) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 16969 INFO (qtp14679347-114) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1575668319514525696
[junit4] 2> 16969 INFO (qtp9876881-105) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1575668319514525696
[junit4] 2> 16983 INFO (searcherExecutor-41-thread-1) [ ] o.a.s.c.SolrCore [collection1_collection_shard1_replica_n1] Registered new searcher Searcher@9a4cbf[collection1_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 17022 INFO (qtp14679347-114) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 17022 INFO (qtp14679347-114) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 17023 INFO (qtp14679347-114) [ ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:39493/solr/collection1_collection_shard2_replica_n3/
[junit4] 2> 17023 INFO (qtp14679347-114) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 17023 INFO (qtp14679347-114) [ ] o.a.s.c.SyncStrategy https://127.0.0.1:39493/solr/collection1_collection_shard2_replica_n3/ has no replicas
[junit4] 2> 17023 INFO (qtp14679347-114) [ ] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 17025 INFO (qtp9876881-105) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 17026 INFO (qtp9876881-105) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 17026 INFO (qtp9876881-105) [ ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:41873/solr/collection1_collection_shard1_replica_n1/
[junit4] 2> 17029 INFO (qtp9876881-105) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 17029 INFO (qtp9876881-105) [ ] o.a.s.c.SyncStrategy https://127.0.0.1:41873/solr/collection1_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 17029 INFO (qtp9876881-105) [ ] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 17036 INFO (qtp9876881-105) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:41873/solr/collection1_collection_shard1_replica_n1/ shard1
[junit4] 2> 17036 INFO (qtp14679347-114) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:39493/solr/collection1_collection_shard2_replica_n3/ shard2
[junit4] 2> 17139 INFO (zkCallback-22-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 17139 INFO (zkCallback-22-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 17139 INFO (zkCallback-24-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 17139 INFO (zkCallback-24-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 17187 INFO (qtp9876881-105) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 17189 INFO (qtp14679347-114) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 17189 INFO (qtp9876881-105) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection1_collection_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1907
[junit4] 2> 17191 INFO (qtp14679347-114) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_collection_shard2_replica_n3&action=CREATE&numShards=2&collection=collection1_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1901
[junit4] 2> 17211 INFO (qtp6035350-101) [ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 17296 INFO (zkCallback-22-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 17296 INFO (zkCallback-24-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 17296 INFO (zkCallback-22-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 17296 INFO (zkCallback-24-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 18212 INFO (qtp6035350-101) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=collection1_collection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=3378
[junit4] 2> 18226 INFO (SUITE-StreamExpressionTest-seed#[8AA48C343550DCED]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: collection1_collection failOnTimeout:true timeout (sec):90
[junit4] 2> 18227 INFO (SUITE-StreamExpressionTest-seed#[8AA48C343550DCED]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1_collection
[junit4] 2> 18229 INFO (qtp6035350-102) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :createalias with params collections=collection1_collection&name=collection1&action=CREATEALIAS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 18232 INFO (OverseerCollectionConfigSetProcessor-98479269602066444-127.0.0.1:38617_solr-n_0000000000) [ ] 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> 18335 INFO (qtp6035350-102) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collections=collection1_collection&name=collection1&action=CREATEALIAS&wt=javabin&version=2} status=0 QTime=105
[junit4] 2> 18353 INFO (TEST-StreamExpressionTest.testCopyOf-seed#[8AA48C343550DCED]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCopyOf
[junit4] 2> 18420 INFO (qtp14679347-122) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n3] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:41873/solr/collection1_collection_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1575668321020280832)} 0 14
[junit4] 2> 18428 INFO (qtp14679347-114) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575668321044398080,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 18428 INFO (qtp14679347-114) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 18428 INFO (qtp14679347-114) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 18428 INFO (qtp14679347-114) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n3] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:41873/solr/collection1_collection_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 18436 INFO (qtp9876881-106) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575668321052786688,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 18436 INFO (qtp9876881-106) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 18437 INFO (qtp9876881-106) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 18437 INFO (qtp9876881-106) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:41873/solr/collection1_collection_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 18449 INFO (qtp9876881-112) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1575668320999309312),commit=} 0 83
[junit4] 2> 18521 INFO (qtp14679347-115) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n3] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&wt=javabin&version=2}{add=[id_1 (1575668321101021184), id_3 (1575668321108361216), id_4 (1575668321109409792), id_5 (1575668321109409793), id_6 (1575668321110458368), id_7 (1575668321110458369), id_8 (1575668321110458370), id_9 (1575668321111506944), id_11 (1575668321111506945), id_12 (1575668321111506946), ... (125 adds)]} 0 39
[junit4] 2> 18523 INFO (qtp9876881-105) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&wt=javabin&version=2}{add=[id_2 (1575668321084243968), id_10 (1575668321108361216), id_13 (1575668321109409792), id_14 (1575668321109409793), id_16 (1575668321110458368), id_17 (1575668321110458369), id_18 (1575668321110458370), id_21 (1575668321111506944), id_22 (1575668321111506945), id_23 (1575668321111506946), ... (125 adds)]} 0 57
[junit4] 2> 18530 INFO (qtp14679347-120) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575668321151352832,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 18531 INFO (qtp14679347-120) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@12318da commitCommandVersion:1575668321151352832
[junit4] 2> 18531 INFO (qtp9876881-111) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575668321152401408,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 18531 INFO (qtp9876881-111) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@267b45 commitCommandVersion:1575668321152401408
[junit4] 2> 18576 INFO (qtp9876881-111) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1bd87e0[collection1_collection_shard1_replica_n1] main]
[junit4] 2> 18576 INFO (qtp14679347-120) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@160a046[collection1_collection_shard2_replica_n3] main]
[junit4] 2> 18577 INFO (qtp14679347-120) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 18578 INFO (searcherExecutor-41-thread-1) [ ] o.a.s.c.SolrCore [collection1_collection_shard1_replica_n1] Registered new searcher Searcher@1bd87e0[collection1_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C125)))}
[junit4] 2> 18578 INFO (qtp9876881-111) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 18578 INFO (qtp9876881-111) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:41873/solr/collection1_collection_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 48
[junit4] 2> 18579 INFO (searcherExecutor-42-thread-1) [ ] o.a.s.c.SolrCore [collection1_col
[...truncated too long message...]
and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-analytics/test/temp/junit4-J2-20170814_013545_71613855306969074942203.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-analytics/test/temp/junit4-J1-20170814_013545_7165450959440123244949.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 502 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-clustering/test/temp/junit4-J2-20170814_013626_07812566833931094357472.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-clustering/test/temp/junit4-J1-20170814_013626_07812739232955033757531.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-clustering/test/temp/junit4-J0-20170814_013626_0788262826115565431336.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 1131 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-dataimporthandler-extras/test/temp/junit4-J1-20170814_013644_614489235136877071407.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-dataimporthandler-extras/test/temp/junit4-J0-20170814_013644_6144321681354865174467.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 590 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-dataimporthandler/test/temp/junit4-J2-20170814_013651_44015025343988098920662.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 6 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-dataimporthandler/test/temp/junit4-J1-20170814_013651_4406211069244792074212.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-dataimporthandler/test/temp/junit4-J0-20170814_013651_44014747412433575173197.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 503 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-cell/test/temp/junit4-J0-20170814_013713_80717008347937607383602.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-cell/test/temp/junit4-J2-20170814_013713_8072112497578020200028.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-cell/test/temp/junit4-J1-20170814_013713_80713465017861175153447.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 507 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-langid/test/temp/junit4-J0-20170814_013723_62111655893623273886166.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-langid/test/temp/junit4-J1-20170814_013723_62110703068621939423078.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 589 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/temp/junit4-J2-20170814_013730_20113161764308879426351.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/temp/junit4-J1-20170814_013730_201169069304233745296.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 6 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/temp/junit4-J0-20170814_013730_2016700655857106264300.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 538 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-uima/test/temp/junit4-J1-20170814_013805_76514500058056149632094.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-uima/test/temp/junit4-J0-20170814_013805_7652593087460694536626.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 494 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-velocity/test/temp/junit4-J0-20170814_013816_25314384167474717915858.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 28962 lines...]
[JENKINS] Lucene-Solr-master-Linux (64bit/jdk1.8.0_144) - Build #
20315 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/20315/
Java: 64bit/jdk1.8.0_144 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testParallelExecutorStream
Error Message:
Error from server at https://127.0.0.1:40329/solr/mainCorpus_shard2_replica_n3: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 404 </title> </head> <body> <h2>HTTP ERROR: 404</h2> <p>Problem accessing /solr/mainCorpus_shard2_replica_n3/update. Reason: <pre> Can not find: /solr/mainCorpus_shard2_replica_n3/update</pre></p> <hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.14.v20161028</a><hr/> </body> </html>
Stack Trace:
org.apache.solr.client.solrj.impl.CloudSolrClient$RouteException: Error from server at https://127.0.0.1:40329/solr/mainCorpus_shard2_replica_n3: Expected mime type application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
<title>Error 404 </title>
</head>
<body>
<h2>HTTP ERROR: 404</h2>
<p>Problem accessing /solr/mainCorpus_shard2_replica_n3/update. Reason:
<pre> Can not find: /solr/mainCorpus_shard2_replica_n3/update</pre></p>
<hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.14.v20161028</a><hr/>
</body>
</html>
at __randomizedtesting.SeedInfo.seed([84B7A85E6CBD8BE7:39A0DD475591B6BA]:0)
at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:539)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:993)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:862)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:793)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178)
at org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:233)
at org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testParallelExecutorStream(StreamExpressionTest.java:6885)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
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:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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 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:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:40329/solr/mainCorpus_shard2_replica_n3: Expected mime type application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
<title>Error 404 </title>
</head>
<body>
<h2>HTTP ERROR: 404</h2>
<p>Problem accessing /solr/mainCorpus_shard2_replica_n3/update. Reason:
<pre> Can not find: /solr/mainCorpus_shard2_replica_n3/update</pre></p>
<hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.14.v20161028</a><hr/>
</body>
</html>
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:591)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
at org.apache.solr.client.solrj.impl.CloudSolrClient.lambda$directUpdate$0(CloudSolrClient.java:516)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
... 1 more
Build Log:
[...truncated 13832 lines...]
[junit4] Suite: org.apache.solr.client.solrj.io.stream.StreamExpressionTest
[junit4] 2> 14440 INFO (SUITE-StreamExpressionTest-seed#[84B7A85E6CBD8BE7]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_84B7A85E6CBD8BE7-001/init-core-data-001
[junit4] 2> 14441 WARN (SUITE-StreamExpressionTest-seed#[84B7A85E6CBD8BE7]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=6 numCloses=6
[junit4] 2> 14441 INFO (SUITE-StreamExpressionTest-seed#[84B7A85E6CBD8BE7]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 14441 INFO (SUITE-StreamExpressionTest-seed#[84B7A85E6CBD8BE7]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 14448 INFO (SUITE-StreamExpressionTest-seed#[84B7A85E6CBD8BE7]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_84B7A85E6CBD8BE7-001/tempDir-001
[junit4] 2> 14479 INFO (SUITE-StreamExpressionTest-seed#[84B7A85E6CBD8BE7]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 14507 INFO (Thread-43) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 14507 INFO (Thread-43) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 14607 INFO (SUITE-StreamExpressionTest-seed#[84B7A85E6CBD8BE7]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:46811
[junit4] 2> 14767 ERROR (Thread-43) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 14858 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15de012b92a0000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 14887 INFO (jetty-launcher-7-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 14893 INFO (jetty-launcher-7-thread-2) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 14901 INFO (jetty-launcher-7-thread-3) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 14926 INFO (jetty-launcher-7-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@391b686e{/solr,null,AVAILABLE}
[junit4] 2> 14955 INFO (jetty-launcher-7-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4c588f8a{/solr,null,AVAILABLE}
[junit4] 2> 14989 INFO (jetty-launcher-7-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4ada4cd9{SSL,[ssl, http/1.1]}{127.0.0.1:39669}
[junit4] 2> 14989 INFO (jetty-launcher-7-thread-2) [ ] o.e.j.s.Server Started @16447ms
[junit4] 2> 14989 INFO (jetty-launcher-7-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39669}
[junit4] 2> 14989 ERROR (jetty-launcher-7-thread-2) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 14989 INFO (jetty-launcher-7-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 14989 INFO (jetty-launcher-7-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 14990 INFO (jetty-launcher-7-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 14990 INFO (jetty-launcher-7-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-14T09:28:30.163Z
[junit4] 2> 14990 INFO (jetty-launcher-7-thread-2) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 15025 INFO (jetty-launcher-7-thread-4) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 15056 INFO (jetty-launcher-7-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7e75fdf2{/solr,null,AVAILABLE}
[junit4] 2> 15121 INFO (jetty-launcher-7-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@9d5a513{/solr,null,AVAILABLE}
[junit4] 2> 15130 INFO (jetty-launcher-7-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 15130 INFO (jetty-launcher-7-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5b1f3951{SSL,[ssl, http/1.1]}{127.0.0.1:40837}
[junit4] 2> 15130 INFO (jetty-launcher-7-thread-3) [ ] o.e.j.s.Server Started @16588ms
[junit4] 2> 15130 INFO (jetty-launcher-7-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40837}
[junit4] 2> 15131 INFO (jetty-launcher-7-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7d22bb81{SSL,[ssl, http/1.1]}{127.0.0.1:34183}
[junit4] 2> 15131 INFO (jetty-launcher-7-thread-1) [ ] o.e.j.s.Server Started @16589ms
[junit4] 2> 15131 INFO (jetty-launcher-7-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34183}
[junit4] 2> 15131 ERROR (jetty-launcher-7-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 15131 INFO (jetty-launcher-7-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 15131 INFO (jetty-launcher-7-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 15131 INFO (jetty-launcher-7-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 15131 INFO (jetty-launcher-7-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-14T09:28:30.304Z
[junit4] 2> 15132 INFO (jetty-launcher-7-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1d3ec4ee{SSL,[ssl, http/1.1]}{127.0.0.1:40329}
[junit4] 2> 15132 INFO (jetty-launcher-7-thread-4) [ ] o.e.j.s.Server Started @16590ms
[junit4] 2> 15132 INFO (jetty-launcher-7-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40329}
[junit4] 2> 15132 ERROR (jetty-launcher-7-thread-4) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 15132 INFO (jetty-launcher-7-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 15132 INFO (jetty-launcher-7-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 15132 INFO (jetty-launcher-7-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 15132 INFO (jetty-launcher-7-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-14T09:28:30.305Z
[junit4] 2> 15134 ERROR (jetty-launcher-7-thread-3) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 15134 INFO (jetty-launcher-7-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 15134 INFO (jetty-launcher-7-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 15134 INFO (jetty-launcher-7-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 15134 INFO (jetty-launcher-7-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-14T09:28:30.307Z
[junit4] 2> 15247 INFO (jetty-launcher-7-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46811/solr
[junit4] 2> 15251 INFO (jetty-launcher-7-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 15251 INFO (jetty-launcher-7-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 15253 INFO (jetty-launcher-7-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 15355 INFO (jetty-launcher-7-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46811/solr
[junit4] 2> 15368 INFO (jetty-launcher-7-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46811/solr
[junit4] 2> 15382 INFO (jetty-launcher-7-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46811/solr
[junit4] 2> 15424 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15de012b92a0006, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 15432 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15de012b92a0005, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 15621 WARN (jetty-launcher-7-thread-2) [n:127.0.0.1:39669_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 15626 WARN (jetty-launcher-7-thread-3) [n:127.0.0.1:40837_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 15626 WARN (jetty-launcher-7-thread-4) [n:127.0.0.1:40329_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 15627 WARN (jetty-launcher-7-thread-1) [n:127.0.0.1:34183_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 15665 INFO (jetty-launcher-7-thread-2) [n:127.0.0.1:39669_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 15665 INFO (jetty-launcher-7-thread-4) [n:127.0.0.1:40329_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 15666 INFO (jetty-launcher-7-thread-3) [n:127.0.0.1:40837_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 15667 INFO (jetty-launcher-7-thread-1) [n:127.0.0.1:34183_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 15669 INFO (jetty-launcher-7-thread-2) [n:127.0.0.1:39669_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:39669_solr
[junit4] 2> 15673 INFO (jetty-launcher-7-thread-2) [n:127.0.0.1:39669_solr ] o.a.s.c.Overseer Overseer (id=98481137892589578-127.0.0.1:39669_solr-n_0000000000) starting
[junit4] 2> 15673 INFO (jetty-launcher-7-thread-4) [n:127.0.0.1:40329_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40329_solr
[junit4] 2> 15673 INFO (jetty-launcher-7-thread-3) [n:127.0.0.1:40837_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40837_solr
[junit4] 2> 15673 INFO (jetty-launcher-7-thread-1) [n:127.0.0.1:34183_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34183_solr
[junit4] 2> 15680 INFO (zkCallback-28-thread-1-processing-n:127.0.0.1:34183_solr) [n:127.0.0.1:34183_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 15707 INFO (zkCallback-29-thread-1-processing-n:127.0.0.1:40837_solr) [n:127.0.0.1:40837_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 15712 INFO (zkCallback-27-thread-1-processing-n:127.0.0.1:39669_solr) [n:127.0.0.1:39669_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 15730 INFO (zkCallback-29-thread-1-processing-n:127.0.0.1:40837_solr) [n:127.0.0.1:40837_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
[junit4] 2> 15730 INFO (zkCallback-27-thread-1-processing-n:127.0.0.1:39669_solr) [n:127.0.0.1:39669_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
[junit4] 2> 15730 INFO (zkCallback-28-thread-1-processing-n:127.0.0.1:34183_solr) [n:127.0.0.1:34183_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
[junit4] 2> 15732 INFO (zkCallback-26-thread-1-processing-n:127.0.0.1:40329_solr) [n:127.0.0.1:40329_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 15988 INFO (jetty-launcher-7-thread-2) [n:127.0.0.1:39669_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39669_solr
[junit4] 2> 15990 INFO (zkCallback-27-thread-1-processing-n:127.0.0.1:39669_solr) [n:127.0.0.1:39669_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 15990 INFO (zkCallback-29-thread-1-processing-n:127.0.0.1:40837_solr) [n:127.0.0.1:40837_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 15990 INFO (zkCallback-28-thread-1-processing-n:127.0.0.1:34183_solr) [n:127.0.0.1:34183_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 15990 INFO (zkCallback-26-thread-1-processing-n:127.0.0.1:40329_solr) [n:127.0.0.1:40329_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 16037 INFO (jetty-launcher-7-thread-1) [n:127.0.0.1:34183_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34183.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60a04542
[junit4] 2> 16049 INFO (jetty-launcher-7-thread-1) [n:127.0.0.1:34183_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34183.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60a04542
[junit4] 2> 16049 INFO (jetty-launcher-7-thread-1) [n:127.0.0.1:34183_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34183.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60a04542
[junit4] 2> 16054 INFO (jetty-launcher-7-thread-1) [n:127.0.0.1:34183_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_84B7A85E6CBD8BE7-001/tempDir-001/node1/.
[junit4] 2> 16143 INFO (jetty-launcher-7-thread-3) [n:127.0.0.1:40837_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40837.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60a04542
[junit4] 2> 16232 INFO (jetty-launcher-7-thread-3) [n:127.0.0.1:40837_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40837.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60a04542
[junit4] 2> 16232 INFO (jetty-launcher-7-thread-3) [n:127.0.0.1:40837_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40837.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60a04542
[junit4] 2> 16243 INFO (jetty-launcher-7-thread-3) [n:127.0.0.1:40837_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_84B7A85E6CBD8BE7-001/tempDir-001/node3/.
[junit4] 2> 16358 INFO (jetty-launcher-7-thread-4) [n:127.0.0.1:40329_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40329.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60a04542
[junit4] 2> 16363 INFO (jetty-launcher-7-thread-4) [n:127.0.0.1:40329_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40329.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60a04542
[junit4] 2> 16363 INFO (jetty-launcher-7-thread-4) [n:127.0.0.1:40329_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40329.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60a04542
[junit4] 2> 16365 INFO (jetty-launcher-7-thread-4) [n:127.0.0.1:40329_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_84B7A85E6CBD8BE7-001/tempDir-001/node4/.
[junit4] 2> 16374 INFO (jetty-launcher-7-thread-2) [n:127.0.0.1:39669_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39669.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60a04542
[junit4] 2> 16378 INFO (jetty-launcher-7-thread-2) [n:127.0.0.1:39669_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39669.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60a04542
[junit4] 2> 16378 INFO (jetty-launcher-7-thread-2) [n:127.0.0.1:39669_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39669.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60a04542
[junit4] 2> 16379 INFO (jetty-launcher-7-thread-2) [n:127.0.0.1:39669_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_84B7A85E6CBD8BE7-001/tempDir-001/node2/.
[junit4] 2> 16676 INFO (SUITE-StreamExpressionTest-seed#[84B7A85E6CBD8BE7]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 16677 INFO (SUITE-StreamExpressionTest-seed#[84B7A85E6CBD8BE7]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46811/solr ready
[junit4] 2> 16942 INFO (qtp302434633-174) [n:127.0.0.1:39669_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=collection1_collection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 16995 INFO (OverseerThreadFactory-59-thread-1-processing-n:127.0.0.1:39669_solr) [n:127.0.0.1:39669_solr ] o.a.s.c.CreateCollectionCmd Create collection collection1_collection
[junit4] 2> 17142 INFO (OverseerStateUpdate-98481137892589578-127.0.0.1:39669_solr-n_0000000000) [n:127.0.0.1:39669_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:34183/solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 17155 INFO (OverseerStateUpdate-98481137892589578-127.0.0.1:39669_solr-n_0000000000) [n:127.0.0.1:39669_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1_collection",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1_collection_shard2_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:39669/solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 17412 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_collection_shard2_replica_n3&action=CREATE&numShards=2&collection=collection1_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 17413 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 17453 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection1_collection_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 17453 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 17557 INFO (zkCallback-27-thread-1-processing-n:127.0.0.1:39669_solr) [n:127.0.0.1:39669_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 17557 INFO (zkCallback-28-thread-1-processing-n:127.0.0.1:34183_solr) [n:127.0.0.1:34183_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 17558 INFO (zkCallback-27-thread-2-processing-n:127.0.0.1:39669_solr) [n:127.0.0.1:39669_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 17558 INFO (zkCallback-28-thread-2-processing-n:127.0.0.1:34183_solr) [n:127.0.0.1:34183_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 18447 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 18462 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 18465 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.s.IndexSchema [collection1_collection_shard2_replica_n3] Schema name=test
[junit4] 2> 18468 WARN (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieIntField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 18470 WARN (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieFloatField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 18473 WARN (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieLongField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 18475 WARN (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieDoubleField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 18483 WARN (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieDateField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 18488 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.s.IndexSchema [collection1_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 18567 WARN (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.GeoHashField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 18567 WARN (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.GeoHashField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 18572 WARN (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.LatLonType]. Please consult documentation how to replace it accordingly.
[junit4] 2> 18572 WARN (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.LatLonType]. Please consult documentation how to replace it accordingly.
[junit4] 2> 18577 WARN (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 18577 WARN (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 18577 WARN (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 18577 WARN (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 18578 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 18578 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 18580 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.CoreContainer Creating SolrCore 'collection1_collection_shard2_replica_n3' using configuration from collection collection1_collection, trusted=true
[junit4] 2> 18580 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'collection1_collection_shard1_replica_n1' using configuration from collection collection1_collection, trusted=true
[junit4] 2> 18580 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34183.solr.core.collection1_collection.shard1.replica_n1' (registry 'solr.core.collection1_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60a04542
[junit4] 2> 18580 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39669.solr.core.collection1_collection.shard2.replica_n3' (registry 'solr.core.collection1_collection.shard2.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60a04542
[junit4] 2> 18581 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 18581 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 18581 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrCore [[collection1_collection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_84B7A85E6CBD8BE7-001/tempDir-001/node1/collection1_collection_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_84B7A85E6CBD8BE7-001/tempDir-001/node1/./collection1_collection_shard1_replica_n1/data/]
[junit4] 2> 18581 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.SolrCore [[collection1_collection_shard2_replica_n3] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_84B7A85E6CBD8BE7-001/tempDir-001/node2/collection1_collection_shard2_replica_n3], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_84B7A85E6CBD8BE7-001/tempDir-001/node2/./collection1_collection_shard2_replica_n3/data/]
[junit4] 2> 18631 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 18631 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 18632 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 18632 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 18634 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@2658b7cd[collection1_collection_shard2_replica_n3] main]
[junit4] 2> 18637 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 18637 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 18638 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 18640 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1575698210554380288
[junit4] 2> 18643 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 18643 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 18644 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 18644 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 18646 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@177e1b5e[collection1_collection_shard1_replica_n1] main]
[junit4] 2> 18650 INFO (searcherExecutor-67-thread-1-processing-n:127.0.0.1:39669_solr x:collection1_collection_shard2_replica_n3 s:shard2 c:collection1_collection r:core_node4) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.SolrCore [collection1_collection_shard2_replica_n3] Registered new searcher Searcher@2658b7cd[collection1_collection_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 18650 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 18650 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 18651 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 18651 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1575698210565914624
[junit4] 2> 18666 INFO (searcherExecutor-68-thread-1-processing-n:127.0.0.1:34183_solr x:collection1_collection_shard1_replica_n1 s:shard1 c:collection1_collection r:core_node2) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrCore [collection1_collection_shard1_replica_n1] Registered new searcher Searcher@177e1b5e[collection1_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 18668 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 18669 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 18670 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:34183/solr/collection1_collection_shard1_replica_n1/
[junit4] 2> 18668 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 18671 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 18673 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 18674 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:34183/solr/collection1_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 18674 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 18671 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:39669/solr/collection1_collection_shard2_replica_n3/
[junit4] 2> 18674 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 18674 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.SyncStrategy https://127.0.0.1:39669/solr/collection1_collection_shard2_replica_n3/ has no replicas
[junit4] 2> 18674 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 18694 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:34183/solr/collection1_collection_shard1_replica_n1/ shard1
[junit4] 2> 18694 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:39669/solr/collection1_collection_shard2_replica_n3/ shard2
[junit4] 2> 18800 INFO (zkCallback-27-thread-1-processing-n:127.0.0.1:39669_solr) [n:127.0.0.1:39669_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 18800 INFO (zkCallback-28-thread-1-processing-n:127.0.0.1:34183_solr) [n:127.0.0.1:34183_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 18807 INFO (zkCallback-28-thread-2-processing-n:127.0.0.1:34183_solr) [n:127.0.0.1:34183_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 18807 INFO (zkCallback-27-thread-2-processing-n:127.0.0.1:39669_solr) [n:127.0.0.1:39669_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 18845 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 18846 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 18847 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_collection_shard2_replica_n3&action=CREATE&numShards=2&collection=collection1_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1435
[junit4] 2> 18848 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection1_collection_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1395
[junit4] 2> 18870 INFO (qtp302434633-174) [n:127.0.0.1:39669_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 18960 INFO (zkCallback-28-thread-2-processing-n:127.0.0.1:34183_solr) [n:127.0.0.1:34183_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 18960 INFO (zkCallback-28-thread-1-processing-n:127.0.0.1:34183_solr) [n:127.0.0.1:34183_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 18960 INFO (zkCallback-27-thread-2-processing-n:127.0.0.1:39669_solr) [n:127.0.0.1:39669_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 18960 INFO (zkCallback-27-thread-1-processing-n:127.0.0.1:39669_solr) [n:127.0.0.1:39669_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 18998 INFO (OverseerCollectionConfigSetProcessor-98481137892589578-127.0.0.1:39669_solr-n_0000000000) [n:127.0.0.1:39669_solr ] 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> 19870 INFO (qtp302434633-174) [n:127.0.0.1:39669_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=collection1_collection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=2928
[junit4] 2> 19875 INFO (SUITE-StreamExpressionTest-seed#[84B7A85E6CBD8BE7]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: collection1_collection failOnTimeout:true timeout (sec):90
[junit4] 2> 19876 INFO (SUITE-StreamExpressionTest-seed#[84B7A85E6CBD8BE7]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1_collection
[junit4] 2> 19879 INFO (qtp302434633-179) [n:127.0.0.1:39669_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :createalias with params collections=collection1_collection&name=collection1&action=CREATEALIAS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 19993 INFO (qtp302434633-179) [n:127.0.0.1:39669_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collections=collection1_collection&name=collection1&action=CREATEALIAS&wt=javabin&version=2} status=0 QTime=114
[junit4] 2> 20056 INFO (TEST-StreamExpressionTest.testCorrelationStream-seed#[84B7A85E6CBD8BE7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCorrelationStream
[junit4] 2> 20220 INFO (qtp1808133421-190) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:39669/solr/collection1_collection_shard2_replica_n3/&wt=javabin&version=2}{deleteByQuery=*:* (-1575698212202741760)} 0 7
[junit4] 2> 20267 INFO (qtp302434633-179) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575698212260413440,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 20267 INFO (qtp302434633-179) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 20268 INFO (qtp302434633-179) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 20268 INFO (qtp302434633-179) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n3] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:39669/solr/collection1_collection_shard2_replica_n3/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 2
[junit4] 2> 20278 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575698212271947776,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 20278 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 20279 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 20279 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:39669/solr/collection1_collection_shard2_replica_n3/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 1
[junit4] 2> 20282 INFO (qtp302434633-181) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n3] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1575698212134584320),commit=} 0 216
[junit4] 2> 20538 INFO (qtp1808133421-184) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&wt=javabin&version=2}{add=[id_2 (1575698212374708224), id_10 (1575698212416651264), id_13 (1575698212417699840), id_14 (1575698212417699841), id_16 (1575698212418748416), id_17 (1575698212418748417), id_18 (1575698212418748418), id_21 (1575698212419796992), id_22 (1575698212419796993), id_23 (1575698212419796994), ... (125 adds)]} 0 162
[junit4] 2> 20557 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n3] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&wt=javabin&version=2}{add=[id_1 (1575698212308647936), id_3 (1575698212430282752), id_4 (1575698212430282753), id_5 (1575698212430282754), id_6 (1575698212431331328), id_7 (1575698212431331329), id_8 (1575698212431331330), id_9 (1575698212432379904), id_11 (1575698212465934336), id_12 (1575698212465934337), ... (125 adds)]} 0 244
[junit4] 2> 20605 INFO (qtp1808133421-186) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575698212614832128,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 20607 INFO (qtp1808133421-186) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@75fe049 commitCommandVersion:1575698212614832128
[junit4] 2> 20638 INFO (qtp302434633-181) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575698212649435136,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 20642 INFO (qtp302434633-181) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3857b45c commitCommandVersion:1575698212649435136
[junit4] 2> 20686 INFO (qtp1808133421-186) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5a3e4752[collection1_collection_shard1_replica_n1] main]
[junit4] 2> 20687 INFO (qtp1808133421-186) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 20688 INFO (searcherExecutor-68-thread-1-processing-n:127.0.0.1:34183_solr x:collection1_collection_shard1_replica_n1 s:shard1 c:collection1_collection r:core_node2) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrCore [collection1_collection_shard1_replica_n1] Registered new searcher Searcher@5a3e4752[collection1_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C125)))}
[junit4] 2> 20688 INFO (qtp1808133421-186) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:34183/solr/collection1_collection_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 82
[junit4] 2> 20697 INFO (qtp302434633-181) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@95cd6a9[collection1_collection_shard2_replica_n3] main]
[junit4] 2> 20698 INFO (qtp302434633-181) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 20699 INFO (searcherExecutor-67-thread-1-processing-n:127.0.0.1:39669_solr x:collection1_collection_shard2_replica_n3 s:shard2 c:collection1_collection r:core_node4) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.SolrCore [collection1_collection_shard2_replica_n3] Registered new searcher Searcher@95cd6a9[collection1_collection_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C125)))}
[junit4] 2> 20706 INFO (qtp302434633-181) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n3] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:34183/solr/collection1_collection_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 68
[junit4] 2> 20708 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2}{commit=} 0 136
[junit4] 2> 20755 INFO (qtp1808133421-186) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/stream params={indent=off&expr=let(a%3Dtimeseries(collection1,+q%3D"*:*",+start%3D"2013-01-01T01:00:00.000Z",+end%3D"2016-12-01T01:00:00.000Z",+gap%3D"%2B1YEAR",+field%3D"test_dt",+count(*),+sum(price_f),+max(price_f),+min(price_f)),+b%3Dselect(timeseries(collection1,+q%3D"*:*",+start%3D"2013-01-01T01:00:00.000Z",+end%3D"2016-12-01T01:00:00.000Z",+gap%3D"%2B1YEAR",+field%3D"test_dt",+count(*),+sum(price_f),+max(price_f),+min(price_f)),mult(-1,+count(*))+as+nvalue),+c%3Dcol(a,+count(*)),+d%3Dcol(b,+nvalue),+tuple(corr%3Dcorr(c,d)))&wt=json&version=2.2} status=0 QTime=15
[junit4] 2> 20804 INFO (qtp1808133421-186) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 20805 INFO (qtp1808133421-186) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46811/solr ready
[junit4] 2> 20925 INFO (qtp302434633-177) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1_collection:4&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&collection=collection1_collection&shard.url=https://127.0.0.1:39669/solr/collection1_collection_shard2_replica_n3/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1502702915987&isShard=true&wt=javabin} hits=125 status=0 QTime=19
[junit4] 2> 20932 INFO (qtp1808133421-189) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1_collection:4&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&collection=collection1_collection&shard.url=https://127.0.0.1:34183/solr/collection1_collection_shard1_replica_n1/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1502702915987&isShard=true&wt=javabin} hits=125 status=0 QTime=69
[junit4] 2> 20943 INFO (qtp1808133421-190) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&_stateVer_=collection1_collection:4&field=test_dt&rows=0&wt=javabin&version=2} hits=250 status=0 QTime=129
[junit4] 2> 20950 INFO (qtp1808133421-182) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1_collection:4&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&collection=collection1_collection&shard.url=https://127.0.0.1:34183/solr/collection1_collection_shard1_replica_n1/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1502702916118&isShard=true&wt=javabin} hits=125 status=0 QTime=2
[junit4] 2> 20953 INFO (qtp302434633-181) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1_collection:4&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&collection=collection1_collection&shard.url=https://127.0.0.1:39669/solr/collection1_collection_shard2_replica_n3/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1502702916118&isShard=true&wt=javabin} hits=125 status=0 QTime=6
[junit4] 2> 20956 INFO (qtp1808133421-183) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&_stateVer_=collection1_collection:4&field=test_dt&rows=0&wt=javabin&version=2} hits=250 status=0 QTime=10
[junit4] 2> 20976 INFO (TEST-StreamExpressionTest.testCorrelationStream-seed#[84B7A85E6CBD8BE7]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCorrelationStream
[junit4] 2> 20993 INFO (TEST-StreamExpressionTest.testParallelHavingStream-seed#[84B7A85E6CBD8BE7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testParallelHavingStream
[junit4] 2> 21002 INFO (qtp302434633-176) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@4b782997[collection1_collection_shard2_replica_n3] realtime]
[junit4] 2> 21002 INFO (qtp1808133421-301) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@434a38b3[collection1_collection_shard1_replica_n1] realtime]
[junit4] 2> 21003 INFO (qtp1808133421-301) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:39669/solr/collection1_collection_shard2_replica_n3/&wt=javabin&version=2}{deleteByQuery=*:* (-1575698213024825344)} 0 6
[junit4] 2> 21005 INFO (qtp302434633-174) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575698213034262528,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 21005 INFO (qtp1808133421-186) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575698213034262528,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 21005 INFO (qtp302434633-174) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3857b45c commitCommandVersion:1575698213034262528
[junit4] 2> 21005 INFO (qtp1808133421-186) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@75fe049 commitCommandVersion:1575698213034262528
[junit4] 2> 21006 INFO (qtp302434633-174) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@450db013[collection1_collection_shard2_replica_n3] main]
[junit4] 2> 21006 INFO (qtp1808133421-186) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@245e2fc0[collection1_collection_shard1_replica_n1] main]
[junit4] 2> 21006 INFO (qtp302434633-174) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 21007 INFO (searcherExecutor-67-thread-1-processing-n:127.0.0.1:39669_solr x:collection1_collection_shard2_replica_n3 s:shard2 c:collection1_collection r:core_node4) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.SolrCore [collection1_collection_shard2_replica_n3] Registered new searcher Searcher@450db013[collection1_collection_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 21007 INFO (qtp302434633-174) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n3] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:39669/solr/collection1_collection_shard2_replica_n3/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 2
[junit4] 2> 21007 INFO (searcherExecutor-68-thread-1-processing-n:127.0.0.1:34183_solr x:collection1_collection_shard1_replica_n1 s:shard1 c:collection1_collection r:core_node2) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrCore [collection1_collection_shard1_replica_n1] Registered new searcher Searcher@245e2fc0[collection1_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 21010 INFO (qtp1808133421-186) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 21010 INFO (qtp1808133421-186) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:39669/solr/collection1_collection_shard2_replica_n3/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 5
[junit4] 2> 21011 INFO (qtp302434633-176) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n3] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1575698213023776768),commit=} 0 16
[junit4] 2> 21022 INFO (qtp1808133421-189) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&wt=javabin&version=2}{add=[0 (1575698213042651136), 4 (1575698213052088320), 1 (1575698213052088321), 8 (1575698213052088322)]} 0 9
[junit4] 2> 21022 INFO (qtp302434633-179) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n3] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&wt=javabin&version=2}{add=[2 (1575698213042651136), 3 (1575698213051039744), 5 (1575698213052088320), 6 (1575698213052088321), 7 (1575698213052088322), 9 (1575698213052088323)]} 0 9
[junit4] 2> 21030 INFO (qtp302434633-177) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575698213060476928,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 21030 INFO (qtp1808133421-190) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575698213060476928,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 21030 INFO (qtp302434633-177) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3857b45c commitCommandVersion:1575698213060476928
[junit4] 2> 21030 INFO (qtp1808133421-190) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@75fe049 commitCommandVersion:1575698213060476928
[junit4] 2> 21048 INFO (qtp1808133421-190) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@49735b8b[collection1_collection_shard1_replica_n1] main]
[junit4] 2> 21048 INFO (qtp302434633-177) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@66f68a42[collection1_collection_shard2_replica_n3] main]
[junit4] 2> 21048 INFO (qtp302434633-177) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 21049 INFO (qtp1808133421-190) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 21050 INFO (searcherExecutor-68-thread-1-processing-n:127.0.0.1:34183_solr x:collection1_collection_shard1_replica_n1 s:shard1 c:collection1_collection r:core_node2) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrCore [collection1_collection_shard1_replica_n1] Registered new searcher Searcher@49735b8b[collection1_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):C4)))}
[junit4] 2> 21051 INFO (searcherExecutor-67-thread-1-processing-n:127.0.0.1:39669_solr x:collection1_collection_shard2_replica_n3 s:shard2 c:collection1_collection r:core_node4) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.SolrCore [collection1_collection_shard2_replica_n3] Registered new searcher Searcher@66f68a42[collection1_collection_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):C6)))}
[junit4] 2> 21051 INFO (qtp302434633-177) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n3] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:39669/solr/collection1_collection_shard2_replica_n3/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 21
[junit4] 2> 21053 INFO (qtp1808133421-190) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:39669/solr/collection1_collection_shard2_replica_n3/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 23
[junit4] 2> 21054 INFO (qtp302434633-179) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n3] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2}{commit=} 0 29
[junit4] 2> 21074 INFO (TEST-StreamExpressionTest.testParallelHavingStream-seed#[84B7A85E6CBD8BE7]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 21075 INFO (TEST-StreamExpressionTest.testParallelHavingStream-seed#[84B7A85E6CBD8BE7]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46811/solr ready
[junit4] 2> 21113 INFO (qtp1808133421-186) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/stream params={distrib=false&workerID=1&indent=off&numWorkers=2&expr=having(search(collection1,q%3D"*:*",fl%3D"id,a_s,a_i,a_f",sort%3D"a_f+asc",partitionKeys%3Did,zkHost%3D"127.0.0.1:46811/solr"),eq(a_i,val(9)))&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 21113 INFO (qtp302434633-177) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/stream params={distrib=false&workerID=0&indent=off&numWorkers=2&expr=having(search(collection1,q%3D"*:*",fl%3D"id,a_s,a_i,a_f",sort%3D"a_f+asc",partitionKeys%3Did,zkHost%3D"127.0.0.1:46811/solr"),eq(a_i,val(9)))&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 21140 INFO (qtp302434633-175) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc&partitionKeys=id&fq={!hash+workers%3D2+worker%3D0}&wt=json&version=2.2} hits=2 status=0 QTime=7
[junit4] 2> 21140 INFO (qtp302434633-176) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc&partitionKeys=id&fq={!hash+workers%3D2+worker%3D1}&wt=json&version=2.2} hits=4 status=0 QTime=4
[junit4] 2> 21140 INFO (qtp1808133421-188) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc&partitionKeys=id&fq={!hash+workers%3D2+worker%3D1}&wt=json&version=2.2} hits=1 status=0 QTime=3
[junit4] 2> 21140 INFO (qtp1808133421-183) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc&partitionKeys=id&fq={!hash+workers%3D2+worker%3D0}&wt=json&version=2.2} hits=3 status=0 QTime=4
[junit4] 2> 21157 INFO (qtp302434633-180) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/stream params={distrib=false&workerID=0&indent=off&numWorkers=2&expr=having(search(collection1,q%3D"*:*",fl%3D"id,a_s,a_i,a_f",sort%3D"a_f+asc",partitionKeys%3Did,zkHost%3D"127.0.0.1:46811/solr"),and(eq(a_i,val(9)),lt(a_i,val(10))))&wt=json&version=2.2} status=0 QTime=1
[junit4] 2> 21157 INFO (qtp1808133421-183) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/stream params={distrib=false&workerID=1&indent=off&numWorkers=2&expr=having(search(collection1,q%3D"*:*",fl%3D"id,a_s,a_i,a_f",sort%3D"a_f+asc",partitionKeys%3Did,zkHost%3D"127.0.0.1:46811/solr"),and(eq(a_i,val(9)),lt(a_i,val(10))))&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 21167 INFO (qtp302434633-177) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc&partitionKeys=id&fq={!hash+workers%3D2+worker%3D0}&wt=json&version=2.2} hits=2 status=0 QTime=0
[junit4] 2> 21167 INFO (qtp1808133421-186) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc&partitionKeys=id&fq={!hash+workers%3D2+worker%3D0}&wt=json&version=2.2} hits=3 status=0 QTime=0
[junit4] 2> 21174 INFO (qtp302434633-179) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc&partitionKeys=id&fq={!hash+workers%3D2+worker%3D1}&wt=json&version=2.2} hits=4 status=0 QTime=1
[junit4] 2> 21174 INFO (qtp1808133421-188) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc&partitionKeys=id&fq={!hash+workers%3D2+worker%3D1}&wt=json&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 21188 INFO (qtp302434633-177) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/stream params={distrib=false&workerID=0&indent=off&numWorkers=2&expr=having(search(collection1,q%3D"*:*",fl%3D"id,a_s,a_i,a_f",sort%3D"a_f+asc",partitionKeys%3Did,zkHost%3D"127.0.0.1:46811/solr"),or(eq(a_i,val(9)),eq(a_i,val(8))))&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 21188 INFO (qtp1808133421-183) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/stream params={distrib=false&workerID=1&indent=off&numWorkers=2&expr=having(search(collection1,q%3D"*:*",fl%3D"id,a_s,a_i,a_f",sort%3D"a_f+asc",partitionKeys%3Did,zkHost%3D"127.0.0.1:46811/solr"),or(eq(a_i,val(9)),eq(a_i,val(8))))&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 21196 INFO (qtp1808133421-186) [n:127.0.0.1:34183_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc&partitionKeys=id&fq={!hash+workers%3D2+worker%3D1}&wt=json&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 21196 INFO (qtp302434633-181) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+a
[...truncated too long message...]
:destination_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.destination.shard2.leader, tag=1783660648
[junit4] 2> 92041 INFO (coreCloseExecutor-198-thread-1) [n:127.0.0.1:40837_solr c:workQueue s:shard2 r:core_node4 x:workQueue_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.workQueue.shard2.replica_n2, tag=1115236652
[junit4] 2> 92041 INFO (zkCallback-29-thread-2-processing-n:127.0.0.1:40837_solr) [n:127.0.0.1:40837_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 92042 INFO (zkCallback-26-thread-1-processing-n:127.0.0.1:40329_solr) [n:127.0.0.1:40329_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 92042 INFO (zkCallback-27-thread-2-processing-n:127.0.0.1:39669_solr) [n:127.0.0.1:39669_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 92047 INFO (coreCloseExecutor-200-thread-1) [n:127.0.0.1:40329_solr c:destination s:shard1 r:core_node3 x:destination_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.destination.shard1.leader, tag=1448486043
[junit4] 2> 92048 INFO (coreCloseExecutor-199-thread-1) [n:127.0.0.1:39669_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1_collection.shard2.leader, tag=1428428807
[junit4] 2> 92049 INFO (coreCloseExecutor-198-thread-2) [n:127.0.0.1:40837_solr c:mainCorpus s:shard1 r:core_node3 x:mainCorpus_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.mainCorpus.shard1.replica_n1, tag=881235595
[junit4] 2> 92049 INFO (jetty-closer-8-thread-4) [ ] o.a.s.c.Overseer Overseer (id=98481137892589578-127.0.0.1:39669_solr-n_0000000000) closing
[junit4] 2> 92049 INFO (zkCallback-29-thread-2-processing-n:127.0.0.1:40837_solr) [n:127.0.0.1:40837_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 92049 INFO (zkCallback-27-thread-2-processing-n:127.0.0.1:39669_solr) [n:127.0.0.1:39669_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 92051 INFO (OverseerStateUpdate-98481137892589578-127.0.0.1:39669_solr-n_0000000000) [n:127.0.0.1:39669_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:39669_solr
[junit4] 2> 92052 INFO (coreCloseExecutor-198-thread-1) [n:127.0.0.1:40837_solr c:workQueue s:shard2 r:core_node4 x:workQueue_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.workQueue.shard2.leader, tag=1115236652
[junit4] 2> 92052 INFO (coreCloseExecutor-198-thread-2) [n:127.0.0.1:40837_solr c:mainCorpus s:shard1 r:core_node3 x:mainCorpus_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.mainCorpus.shard1.leader, tag=881235595
[junit4] 2> 93542 WARN (zkCallback-28-thread-2-processing-n:127.0.0.1:34183_solr) [n:127.0.0.1:34183_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 93543 INFO (jetty-closer-8-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@391b686e{/solr,null,UNAVAILABLE}
[junit4] 2> 93549 WARN (zkCallback-26-thread-1-processing-n:127.0.0.1:40329_solr) [n:127.0.0.1:40329_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 93550 INFO (jetty-closer-8-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@9d5a513{/solr,null,UNAVAILABLE}
[junit4] 2> 93556 WARN (zkCallback-27-thread-2-processing-n:127.0.0.1:39669_solr) [n:127.0.0.1:39669_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 93556 INFO (jetty-closer-8-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4c588f8a{/solr,null,UNAVAILABLE}
[junit4] 2> 93557 WARN (zkCallback-29-thread-2-processing-n:127.0.0.1:40837_solr) [n:127.0.0.1:40837_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 93557 INFO (jetty-closer-8-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7e75fdf2{/solr,null,UNAVAILABLE}
[junit4] 2> 93557 ERROR (SUITE-StreamExpressionTest-seed#[84B7A85E6CBD8BE7]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 93558 INFO (SUITE-StreamExpressionTest-seed#[84B7A85E6CBD8BE7]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46811 46811
[junit4] 2> 98590 INFO (Thread-43) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46811 46811
[junit4] 2> 98592 WARN (Thread-43) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 70 /solr/aliases.json
[junit4] 2> 10 /solr/configs/conf
[junit4] 2> 4 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 70 /solr/clusterprops.json
[junit4] 2> 70 /solr/clusterstate.json
[junit4] 2> 20 /solr/collections/destination/state.json
[junit4] 2> 20 /solr/collections/parallelDestinationCollection/state.json
[junit4] 2> 20 /solr/collections/destinationCollection/state.json
[junit4] 2> 20 /solr/collections/mainCorpus/state.json
[junit4] 2> 18 /solr/collections/workQueue/state.json
[junit4] 2> 18 /solr/collections/parallelDestinationCollection1/state.json
[junit4] 2> 10 /solr/collections/collection1_collection/state.json
[junit4] 2> 2 /solr/overseer_elect/election/98481137892589578-127.0.0.1:39669_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 70 /solr/live_nodes
[junit4] 2> 70 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_84B7A85E6CBD8BE7-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {expr_s=Lucene50(blocksize=128), join2_s=PostingsFormat(name=Memory), multiDefault=PostingsFormat(name=Memory), subject=PostingsFormat(name=Memory), price_f=PostingsFormat(name=Memory), a1_s=Lucene50(blocksize=128), intDefault=Lucene50(blocksize=128), a_f=Lucene50(blocksize=128), a2_s=Lucene50(blocksize=128), join1_i=Lucene50(blocksize=128), a_i=PostingsFormat(name=LuceneVarGapDocFreqInterval), join3_i=PostingsFormat(name=LuceneVarGapDocFreqInterval), test_i=PostingsFormat(name=Memory), field_i=PostingsFormat(name=Memory), level1_s=Lucene50(blocksize=128), body_t=PostingsFormat(name=LuceneVarGapDocFreqInterval), s_multi=Lucene50(blocksize=128), a_ss=PostingsFormat(name=Memory), col_s=Lucene50(blocksize=128), level2_s=Lucene50(blocksize=128), a_s=Lucene50(blocksize=128), a_t=PostingsFormat(name=Memory), id=PostingsFormat(name=LuceneVarGapDocFreqInterval), text=Lucene50(blocksize=128), timestamp=PostingsFormat(name=Memory), i_multi=PostingsFormat(name=LuceneVarGapDocFreqInterval), b_ls=Lucene50(blocksize=128), test_dt=Lucene50(blocksize=128), test_t=PostingsFormat(name=LuceneVarGapDocFreqInterval), ident_s=Lucene50(blocksize=128), order_i=PostingsFormat(name=Memory), _version_=Lucene50(blocksize=128), side_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), miles_i=Lucene50(blocksize=128)}, docValues:{expr_s=DocValuesFormat(name=Direct), join2_s=DocValuesFormat(name=Lucene70), multiDefault=DocValuesFormat(name=Lucene70), price_f=DocValuesFormat(name=Lucene70), a1_s=DocValuesFormat(name=Asserting), intDefault=DocValuesFormat(name=Asserting), a_f=DocValuesFormat(name=Asserting), a2_s=DocValuesFormat(name=Direct), join1_i=DocValuesFormat(name=Direct), a_i=DocValuesFormat(name=Lucene70), join3_i=DocValuesFormat(name=Lucene70), test_i=DocValuesFormat(name=Lucene70), field_i=DocValuesFormat(name=Lucene70), level1_s=DocValuesFormat(name=Direct), s_multi=DocValuesFormat(name=Direct), a_ss=DocValuesFormat(name=Lucene70), col_s=DocValuesFormat(name=Asserting), level2_s=DocValuesFormat(name=Asserting), a_s=DocValuesFormat(name=Direct), id=DocValuesFormat(name=Lucene70), timestamp=DocValuesFormat(name=Lucene70), i_multi=DocValuesFormat(name=Lucene70), b_ls=DocValuesFormat(name=Asserting), test_dt=DocValuesFormat(name=Direct), ident_s=DocValuesFormat(name=Asserting), order_i=DocValuesFormat(name=Lucene70), _version_=DocValuesFormat(name=Asserting), side_s=DocValuesFormat(name=Lucene70), miles_i=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1583, maxMBSortInHeap=5.200970663128015, sim=RandomSimilarity(queryNorm=true): {}, locale=de-AT, timezone=Africa/Algiers
[junit4] 2> NOTE: Linux 4.10.0-27-generic amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=8,threads=1,free=352295656,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [DocumentAnalysisResponseTest, LargeVolumeJettyTest, HyperbolicCosineEvaluatorTest, TestPolicy, TestDelegationTokenRequest, JsonValidatorTest, SolrExampleJettyTest, AndEvaluatorTest, CommonAdminParamsTest, ConcatOperationTest, TestBatchUpdate, CoalesceEvaluatorTest, StreamExpressionTest]
[junit4] Completed [84/135 (1!)] on J2 in 84.18s, 94 tests, 1 error, 9 skipped <<< FAILURES!
[...truncated 42579 lines...]
[JENKINS-EA] Lucene-Solr-master-Linux (32bit/jdk-9-ea+181) - Build
# 20314 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/20314/
Java: 32bit/jdk-9-ea+181 -server -XX:+UseG1GC --illegal-access=deny
1 tests failed.
FAILED: org.apache.solr.cloud.LeaderElectionContextKeyTest.test
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([30F24A2AA850BE53:B8A675F006ACD3AB]:0)
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertTrue(Assert.java:54)
at org.apache.solr.cloud.LeaderElectionContextKeyTest.test(LeaderElectionContextKeyTest.java:88)
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:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
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:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 12450 lines...]
[junit4] Suite: org.apache.solr.cloud.LeaderElectionContextKeyTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderElectionContextKeyTest_30F24A2AA850BE53-001/init-core-data-001
[junit4] 2> 1577211 WARN (SUITE-LeaderElectionContextKeyTest-seed#[30F24A2AA850BE53]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=39 numCloses=39
[junit4] 2> 1577211 INFO (SUITE-LeaderElectionContextKeyTest-seed#[30F24A2AA850BE53]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1577212 INFO (SUITE-LeaderElectionContextKeyTest-seed#[30F24A2AA850BE53]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1577212 INFO (SUITE-LeaderElectionContextKeyTest-seed#[30F24A2AA850BE53]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1577213 INFO (SUITE-LeaderElectionContextKeyTest-seed#[30F24A2AA850BE53]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderElectionContextKeyTest_30F24A2AA850BE53-001/tempDir-001
[junit4] 2> 1577213 INFO (SUITE-LeaderElectionContextKeyTest-seed#[30F24A2AA850BE53]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1577214 INFO (Thread-2900) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1577214 INFO (Thread-2900) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1577218 ERROR (Thread-2900) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 1577314 INFO (SUITE-LeaderElectionContextKeyTest-seed#[30F24A2AA850BE53]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:32777
[junit4] 2> 1577341 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15ddf2e88e60000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1577343 INFO (jetty-launcher-1698-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1577353 INFO (jetty-launcher-1698-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@bde575{/solr,null,AVAILABLE}
[junit4] 2> 1577355 INFO (jetty-launcher-1698-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@112e8b4{HTTP/1.1,[http/1.1]}{127.0.0.1:40695}
[junit4] 2> 1577355 INFO (jetty-launcher-1698-thread-1) [ ] o.e.j.s.Server Started @1578760ms
[junit4] 2> 1577355 INFO (jetty-launcher-1698-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40695}
[junit4] 2> 1577355 ERROR (jetty-launcher-1698-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1577355 INFO (jetty-launcher-1698-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 1577355 INFO (jetty-launcher-1698-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1577355 INFO (jetty-launcher-1698-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 1577355 INFO (jetty-launcher-1698-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-14T05:19:15.314723Z
[junit4] 2> 1577370 INFO (jetty-launcher-1698-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1577378 INFO (jetty-launcher-1698-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32777/solr
[junit4] 2> 1577440 INFO (jetty-launcher-1698-thread-1) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1577440 INFO (jetty-launcher-1698-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:40695_solr
[junit4] 2> 1577441 INFO (jetty-launcher-1698-thread-1) [ ] o.a.s.c.Overseer Overseer (id=98480157830283267-127.0.0.1:40695_solr-n_0000000000) starting
[junit4] 2> 1577459 INFO (jetty-launcher-1698-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40695_solr
[junit4] 2> 1577470 INFO (zkCallback-1705-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1577588 INFO (jetty-launcher-1698-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40695.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10314ca
[junit4] 2> 1577593 INFO (jetty-launcher-1698-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40695.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10314ca
[junit4] 2> 1577593 INFO (jetty-launcher-1698-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40695.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10314ca
[junit4] 2> 1577594 INFO (jetty-launcher-1698-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderElectionContextKeyTest_30F24A2AA850BE53-001/tempDir-001/node1/.
[junit4] 2> 1577640 INFO (SUITE-LeaderElectionContextKeyTest-seed#[30F24A2AA850BE53]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1577647 INFO (SUITE-LeaderElectionContextKeyTest-seed#[30F24A2AA850BE53]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:32777/solr ready
[junit4] 2> 1577657 INFO (qtp27437302-11738) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=config&maxShardsPerNode=1000&name=testCollection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1577672 INFO (OverseerThreadFactory-4636-thread-1) [ ] o.a.s.c.CreateCollectionCmd Create collection testCollection1
[junit4] 2> 1577812 INFO (OverseerStateUpdate-98480157830283267-127.0.0.1:40695_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testCollection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testCollection1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40695/solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 1577814 INFO (OverseerStateUpdate-98480157830283267-127.0.0.1:40695_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testCollection1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"testCollection1_shard2_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40695/solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 1578029 INFO (qtp27437302-11737) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=config&newCollection=true&name=testCollection1_shard1_replica_n1&action=CREATE&numShards=2&collection=testCollection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1578030 INFO (qtp27437302-11737) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1578049 INFO (qtp27437302-11734) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=config&newCollection=true&name=testCollection1_shard2_replica_n3&action=CREATE&numShards=2&collection=testCollection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1578155 INFO (zkCallback-1705-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCollection1/state.json] for collection [testCollection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1578155 INFO (zkCallback-1705-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCollection1/state.json] for collection [testCollection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1579048 INFO (qtp27437302-11737) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1579053 INFO (qtp27437302-11737) [ ] o.a.s.s.IndexSchema [testCollection1_shard1_replica_n1] Schema name=minimal
[junit4] 2> 1579054 INFO (qtp27437302-11737) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1579054 INFO (qtp27437302-11737) [ ] o.a.s.c.CoreContainer Creating SolrCore 'testCollection1_shard1_replica_n1' using configuration from collection testCollection1, trusted=true
[junit4] 2> 1579056 INFO (qtp27437302-11737) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40695.solr.core.testCollection1.shard1.replica_n1' (registry 'solr.core.testCollection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10314ca
[junit4] 2> 1579056 INFO (qtp27437302-11737) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1579056 INFO (qtp27437302-11737) [ ] o.a.s.c.SolrCore [[testCollection1_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderElectionContextKeyTest_30F24A2AA850BE53-001/tempDir-001/node1/testCollection1_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderElectionContextKeyTest_30F24A2AA850BE53-001/tempDir-001/node1/./testCollection1_shard1_replica_n1/data/]
[junit4] 2> 1579065 INFO (qtp27437302-11734) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1579071 INFO (qtp27437302-11734) [ ] o.a.s.s.IndexSchema [testCollection1_shard2_replica_n3] Schema name=minimal
[junit4] 2> 1579072 INFO (qtp27437302-11734) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1579072 INFO (qtp27437302-11734) [ ] o.a.s.c.CoreContainer Creating SolrCore 'testCollection1_shard2_replica_n3' using configuration from collection testCollection1, trusted=true
[junit4] 2> 1579073 INFO (qtp27437302-11734) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40695.solr.core.testCollection1.shard2.replica_n3' (registry 'solr.core.testCollection1.shard2.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10314ca
[junit4] 2> 1579073 INFO (qtp27437302-11734) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1579073 INFO (qtp27437302-11734) [ ] o.a.s.c.SolrCore [[testCollection1_shard2_replica_n3] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderElectionContextKeyTest_30F24A2AA850BE53-001/tempDir-001/node1/testCollection1_shard2_replica_n3], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderElectionContextKeyTest_30F24A2AA850BE53-001/tempDir-001/node1/./testCollection1_shard2_replica_n3/data/]
[junit4] 2> 1579121 INFO (qtp27437302-11734) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1579121 INFO (qtp27437302-11734) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1579122 INFO (qtp27437302-11737) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1579122 INFO (qtp27437302-11737) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1579122 INFO (qtp27437302-11734) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1579122 INFO (qtp27437302-11734) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1579124 INFO (qtp27437302-11734) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@179ece8[testCollection1_shard2_replica_n3] main]
[junit4] 2> 1579124 INFO (qtp27437302-11737) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1579124 INFO (qtp27437302-11737) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1579124 INFO (qtp27437302-11734) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 1579125 INFO (qtp27437302-11734) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 1579125 INFO (qtp27437302-11734) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1579126 INFO (searcherExecutor-4640-thread-1) [ ] o.a.s.c.SolrCore [testCollection1_shard2_replica_n3] Registered new searcher Searcher@179ece8[testCollection1_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1579126 INFO (qtp27437302-11734) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1575682527288360960
[junit4] 2> 1579127 INFO (qtp27437302-11737) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@600c72[testCollection1_shard1_replica_n1] main]
[junit4] 2> 1579129 INFO (qtp27437302-11737) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 1579129 INFO (qtp27437302-11737) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 1579129 INFO (qtp27437302-11734) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1579129 INFO (qtp27437302-11734) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1579130 INFO (qtp27437302-11734) [ ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:40695/solr/testCollection1_shard2_replica_n3/
[junit4] 2> 1579130 INFO (qtp27437302-11734) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1579130 INFO (qtp27437302-11734) [ ] o.a.s.c.SyncStrategy http://127.0.0.1:40695/solr/testCollection1_shard2_replica_n3/ has no replicas
[junit4] 2> 1579130 INFO (qtp27437302-11734) [ ] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 1579130 INFO (qtp27437302-11737) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1579131 INFO (searcherExecutor-4639-thread-1) [ ] o.a.s.c.SolrCore [testCollection1_shard1_replica_n1] Registered new searcher Searcher@600c72[testCollection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1579132 INFO (qtp27437302-11737) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1575682527294652416
[junit4] 2> 1579132 INFO (qtp27437302-11734) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:40695/solr/testCollection1_shard2_replica_n3/ shard2
[junit4] 2> 1579136 INFO (qtp27437302-11737) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1579136 INFO (qtp27437302-11737) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1579136 INFO (qtp27437302-11737) [ ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:40695/solr/testCollection1_shard1_replica_n1/
[junit4] 2> 1579136 INFO (qtp27437302-11737) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1579136 INFO (qtp27437302-11737) [ ] o.a.s.c.SyncStrategy http://127.0.0.1:40695/solr/testCollection1_shard1_replica_n1/ has no replicas
[junit4] 2> 1579136 INFO (qtp27437302-11737) [ ] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 1579138 INFO (qtp27437302-11737) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:40695/solr/testCollection1_shard1_replica_n1/ shard1
[junit4] 2> 1579253 INFO (zkCallback-1705-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCollection1/state.json] for collection [testCollection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1579253 INFO (zkCallback-1705-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCollection1/state.json] for collection [testCollection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1579284 INFO (qtp27437302-11734) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1579287 INFO (qtp27437302-11734) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=config&newCollection=true&name=testCollection1_shard2_replica_n3&action=CREATE&numShards=2&collection=testCollection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1237
[junit4] 2> 1579289 INFO (qtp27437302-11737) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1579291 INFO (qtp27437302-11737) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=config&newCollection=true&name=testCollection1_shard1_replica_n1&action=CREATE&numShards=2&collection=testCollection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1261
[junit4] 2> 1579298 INFO (qtp27437302-11738) [ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 1579393 INFO (zkCallback-1705-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCollection1/state.json] for collection [testCollection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1579393 INFO (zkCallback-1705-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCollection1/state.json] for collection [testCollection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1579677 INFO (OverseerCollectionConfigSetProcessor-98480157830283267-127.0.0.1:40695_solr-n_0000000000) [ ] 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> 1580298 INFO (qtp27437302-11738) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=config&maxShardsPerNode=1000&name=testCollection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=2640
[junit4] 2> 1580298 INFO (qtp27437302-11739) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=config&maxShardsPerNode=1000&name=testCollection2&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1580299 INFO (OverseerThreadFactory-4636-thread-2) [ ] o.a.s.c.CreateCollectionCmd Create collection testCollection2
[junit4] 2> 1580504 INFO (OverseerStateUpdate-98480157830283267-127.0.0.1:40695_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testCollection2",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testCollection2_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40695/solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 1580505 INFO (OverseerStateUpdate-98480157830283267-127.0.0.1:40695_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testCollection2",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"testCollection2_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40695/solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 1580706 INFO (qtp27437302-11732) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=config&newCollection=true&name=testCollection2_shard1_replica_n1&action=CREATE&numShards=2&collection=testCollection2&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1580706 INFO (qtp27437302-11733) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=config&newCollection=true&name=testCollection2_shard2_replica_n2&action=CREATE&numShards=2&collection=testCollection2&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1580821 INFO (zkCallback-1705-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCollection2/state.json] for collection [testCollection2] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1580825 INFO (zkCallback-1705-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCollection2/state.json] for collection [testCollection2] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1581715 INFO (qtp27437302-11732) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1581764 INFO (qtp27437302-11733) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1581767 INFO (qtp27437302-11732) [ ] o.a.s.s.IndexSchema [testCollection2_shard1_replica_n1] Schema name=minimal
[junit4] 2> 1581769 INFO (qtp27437302-11732) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1581769 INFO (qtp27437302-11732) [ ] o.a.s.c.CoreContainer Creating SolrCore 'testCollection2_shard1_replica_n1' using configuration from collection testCollection2, trusted=true
[junit4] 2> 1581769 INFO (qtp27437302-11733) [ ] o.a.s.s.IndexSchema [testCollection2_shard2_replica_n2] Schema name=minimal
[junit4] 2> 1581770 INFO (qtp27437302-11732) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40695.solr.core.testCollection2.shard1.replica_n1' (registry 'solr.core.testCollection2.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10314ca
[junit4] 2> 1581770 INFO (qtp27437302-11732) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1581770 INFO (qtp27437302-11732) [ ] o.a.s.c.SolrCore [[testCollection2_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderElectionContextKeyTest_30F24A2AA850BE53-001/tempDir-001/node1/testCollection2_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderElectionContextKeyTest_30F24A2AA850BE53-001/tempDir-001/node1/./testCollection2_shard1_replica_n1/data/]
[junit4] 2> 1581771 INFO (qtp27437302-11733) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1581771 INFO (qtp27437302-11733) [ ] o.a.s.c.CoreContainer Creating SolrCore 'testCollection2_shard2_replica_n2' using configuration from collection testCollection2, trusted=true
[junit4] 2> 1581771 INFO (qtp27437302-11733) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40695.solr.core.testCollection2.shard2.replica_n2' (registry 'solr.core.testCollection2.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10314ca
[junit4] 2> 1581772 INFO (qtp27437302-11733) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1581772 INFO (qtp27437302-11733) [ ] o.a.s.c.SolrCore [[testCollection2_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderElectionContextKeyTest_30F24A2AA850BE53-001/tempDir-001/node1/testCollection2_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderElectionContextKeyTest_30F24A2AA850BE53-001/tempDir-001/node1/./testCollection2_shard2_replica_n2/data/]
[junit4] 2> 1581836 INFO (qtp27437302-11733) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1581836 INFO (qtp27437302-11733) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1581838 INFO (qtp27437302-11733) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1581838 INFO (qtp27437302-11733) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1581840 INFO (qtp27437302-11733) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6ec1cb[testCollection2_shard2_replica_n2] main]
[junit4] 2> 1581841 INFO (qtp27437302-11733) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 1581841 INFO (qtp27437302-11733) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 1581842 INFO (qtp27437302-11733) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1581842 INFO (qtp27437302-11732) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1581843 INFO (qtp27437302-11732) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1581843 INFO (searcherExecutor-4650-thread-1) [ ] o.a.s.c.SolrCore [testCollection2_shard2_replica_n2] Registered new searcher Searcher@6ec1cb[testCollection2_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1581843 INFO (qtp27437302-11733) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1575682530137341952
[junit4] 2> 1581844 INFO (qtp27437302-11732) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1581844 INFO (qtp27437302-11732) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1581845 INFO (qtp27437302-11732) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1d72436[testCollection2_shard1_replica_n1] main]
[junit4] 2> 1581846 INFO (qtp27437302-11732) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 1581847 INFO (qtp27437302-11732) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 1581848 INFO (qtp27437302-11732) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1581848 INFO (qtp27437302-11733) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1581848 INFO (qtp27437302-11733) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1581848 INFO (qtp27437302-11732) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1575682530142584832
[junit4] 2> 1581848 INFO (qtp27437302-11733) [ ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:40695/solr/testCollection2_shard2_replica_n2/
[junit4] 2> 1581848 INFO (qtp27437302-11733) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1581848 INFO (qtp27437302-11733) [ ] o.a.s.c.SyncStrategy http://127.0.0.1:40695/solr/testCollection2_shard2_replica_n2/ has no replicas
[junit4] 2> 1581848 INFO (qtp27437302-11733) [ ] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 1581849 INFO (searcherExecutor-4649-thread-1) [ ] o.a.s.c.SolrCore [testCollection2_shard1_replica_n1] Registered new searcher Searcher@1d72436[testCollection2_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1581850 INFO (qtp27437302-11733) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:40695/solr/testCollection2_shard2_replica_n2/ shard2
[junit4] 2> 1581853 INFO (qtp27437302-11732) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1581853 INFO (qtp27437302-11732) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1581853 INFO (qtp27437302-11732) [ ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:40695/solr/testCollection2_shard1_replica_n1/
[junit4] 2> 1581853 INFO (qtp27437302-11732) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1581853 INFO (qtp27437302-11732) [ ] o.a.s.c.SyncStrategy http://127.0.0.1:40695/solr/testCollection2_shard1_replica_n1/ has no replicas
[junit4] 2> 1581853 INFO (qtp27437302-11732) [ ] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 1581855 INFO (qtp27437302-11732) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:40695/solr/testCollection2_shard1_replica_n1/ shard1
[junit4] 2> 1581958 INFO (zkCallback-1705-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCollection2/state.json] for collection [testCollection2] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1581958 INFO (zkCallback-1705-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCollection2/state.json] for collection [testCollection2] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1582002 INFO (qtp27437302-11733) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1582004 INFO (qtp27437302-11733) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=config&newCollection=true&name=testCollection2_shard2_replica_n2&action=CREATE&numShards=2&collection=testCollection2&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1298
[junit4] 2> 1582006 INFO (qtp27437302-11732) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1582007 INFO (qtp27437302-11732) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=config&newCollection=true&name=testCollection2_shard1_replica_n1&action=CREATE&numShards=2&collection=testCollection2&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1300
[junit4] 2> 1582009 INFO (qtp27437302-11739) [ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 1582109 INFO (zkCallback-1705-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCollection2/state.json] for collection [testCollection2] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1582109 INFO (zkCallback-1705-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCollection2/state.json] for collection [testCollection2] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1582300 INFO (OverseerCollectionConfigSetProcessor-98480157830283267-127.0.0.1:40695_solr-n_0000000000) [ ] 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> 1583009 INFO (qtp27437302-11739) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=config&maxShardsPerNode=1000&name=testCollection2&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=2710
[junit4] 2> 1583009 INFO (SUITE-LeaderElectionContextKeyTest-seed#[30F24A2AA850BE53]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: testCollection1 failOnTimeout:true timeout (sec):30
[junit4] 2> 1583009 INFO (SUITE-LeaderElectionContextKeyTest-seed#[30F24A2AA850BE53]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: testCollection1
[junit4] 2> 1583010 INFO (SUITE-LeaderElectionContextKeyTest-seed#[30F24A2AA850BE53]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: testCollection2 failOnTimeout:true timeout (sec):30
[junit4] 2> 1583010 INFO (SUITE-LeaderElectionContextKeyTest-seed#[30F24A2AA850BE53]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: testCollection2
[junit4] 2> 1583017 INFO (TEST-LeaderElectionContextKeyTest.test-seed#[30F24A2AA850BE53]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 1583020 INFO (qtp27437302-11738) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testCollection1.shard2.replica_n3, tag=null
[junit4] 2> 1583026 INFO (qtp27437302-11738) [ ] o.a.s.c.SolrCore [testCollection1_shard2_replica_n3] CLOSING SolrCore org.apache.solr.core.SolrCore@a33d84
[junit4] 2> 1583027 INFO (qtp27437302-11738) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testCollection1.shard2.replica_n3, tag=10698116
[junit4] 2> 1583027 INFO (qtp27437302-11738) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testCollection1.shard2.leader, tag=10698116
[junit4] 2> 1583028 INFO (qtp27437302-11738) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=false&core=testCollection1_shard2_replica_n3&deleteDataDir=false&action=UNLOAD&indexInfo=true&wt=javabin&version=2&deleteIndex=false} status=0 QTime=7
[junit4] 2> 1583029 INFO (TEST-LeaderElectionContextKeyTest.test-seed#[30F24A2AA850BE53]) [ ] o.a.s.SolrTestCaseJ4 ###Ending test
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=LeaderElectionContextKeyTest -Dtests.method=test -Dtests.seed=30F24A2AA850BE53 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es-PR -Dtests.timezone=Asia/Pyongyang -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 0.02s J1 | LeaderElectionContextKeyTest.test <<<
[junit4] > Throwable #1: java.lang.AssertionError
[junit4] > at __randomizedtesting.SeedInfo.seed([30F24A2AA850BE53:B8A675F006ACD3AB]:0)
[junit4] > at org.apache.solr.cloud.LeaderElectionContextKeyTest.test(LeaderElectionContextKeyTest.java:88)
[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:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1583032 INFO (jetty-closer-1699-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@112e8b4{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1583037 INFO (jetty-closer-1699-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=27149740
[junit4] 2> 1583037 INFO (jetty-closer-1699-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1583040 INFO (jetty-closer-1699-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1583042 INFO (jetty-closer-1699-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1583043 INFO (jetty-closer-1699-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1583045 INFO (coreCloseExecutor-4659-thread-1) [ ] o.a.s.c.SolrCore [testCollection1_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@522fa3
[junit4] 2> 1583051 INFO (coreCloseExecutor-4659-thread-2) [ ] o.a.s.c.SolrCore [testCollection2_shard2_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@1147e80
[junit4] 2> 1583057 INFO (coreCloseExecutor-4659-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testCollection1.shard1.replica_n1, tag=5386147
[junit4] 2> 1583061 INFO (coreCloseExecutor-4659-thread-3) [ ] o.a.s.c.SolrCore [testCollection2_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@dedbe2
[junit4] 2> 1583062 INFO (coreCloseExecutor-4659-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testCollection1.shard1.leader, tag=5386147
[junit4] 2> 1583066 INFO (coreCloseExecutor-4659-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testCollection2.shard2.replica_n2, tag=18120320
[junit4] 2> 1583070 INFO (coreCloseExecutor-4659-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testCollection2.shard2.leader, tag=18120320
[junit4] 2> 1583074 INFO (coreCloseExecutor-4659-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testCollection2.shard1.replica_n1, tag=14605282
[junit4] 2> 1583079 INFO (coreCloseExecutor-4659-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testCollection2.shard1.leader, tag=14605282
[junit4] 2> 1583079 INFO (jetty-closer-1699-thread-1) [ ] o.a.s.c.Overseer Overseer (id=98480157830283267-127.0.0.1:40695_solr-n_0000000000) closing
[junit4] 2> 1583080 INFO (OverseerStateUpdate-98480157830283267-127.0.0.1:40695_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:40695_solr
[junit4] 2> 1584581 WARN (zkCallback-1705-thread-2) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1584585 INFO (jetty-closer-1699-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@bde575{/solr,null,UNAVAILABLE}
[junit4] 2> 1584586 ERROR (SUITE-LeaderElectionContextKeyTest-seed#[30F24A2AA850BE53]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 1584586 INFO (SUITE-LeaderElectionContextKeyTest-seed#[30F24A2AA850BE53]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:32777 32777
[junit4] 2> 1589617 INFO (Thread-2900) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:32777 32777
[junit4] 2> 1589617 WARN (Thread-2900) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/aliases.json
[junit4] 2> 2 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/collections/testCollection2/state.json
[junit4] 2> 5 /solr/collections/testCollection1/state.json
[junit4] 2> 2 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/live_nodes
[junit4] 2> 2 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderElectionContextKeyTest_30F24A2AA850BE53-001
[junit4] 2> NOTE: test params are: codec=Lucene70, sim=RandomSimilarity(queryNorm=true): {}, locale=es-PR, timezone=Asia/Pyongyang
[junit4] 2> NOTE: Linux 4.10.0-27-generic i386/Oracle Corporation 9 (32-bit)/cpus=8,threads=1,free=309959320,total=531628032
[junit4] 2> NOTE: All tests run in this JVM: [DocValuesTest, TestAnalyzeInfixSuggestions, ConvertedLegacyTest, ShardSplitTest, ResponseHeaderTest, TestLegacyTerms, SolrXmlInZkTest, AnalyticsMergeStrategyTest, BadCopyFieldTest, DistributedMLTComponentTest, BitVectorTest, DistributedDebugComponentTest, TestBM25SimilarityFactory, CreateCollectionCleanupTest, DistributedFacetPivotLargeTest, TestPayloadScoreQParserPlugin, TestXIncludeConfig, JSONWriterTest, TestManagedSchemaThreadSafety, TestStressRecovery, LeaderInitiatedRecoveryOnShardRestartTest, TestCoreDiscovery, IndexSchemaTest, SuggesterTSTTest, TestTrieFacet, IndexSchemaRuntimeFieldTest, SpellCheckComponentTest, AssignTest, TestConfigSetImmutable, TestCursorMarkWithoutUniqueKey, TestCustomDocTransformer, InfixSuggestersTest, AlternateDirectoryTest, CloudExitableDirectoryReaderTest, TestRestManager, TestFieldSortValues, ChaosMonkeySafeLeaderWithPullReplicasTest, TermVectorComponentTest, TestPullReplicaErrorHandling, HealthCheckHandlerTest, SpatialFilterTest, TestChildDocTransformer, SolrGangliaReporterTest, TestOrdValues, TestCloudInspectUtil, CachingDirectoryFactoryTest, AddBlockUpdateTest, TestRawResponseWriter, TestAuthorizationFramework, MetricsHandlerTest, TestElisionMultitermQuery, TestUpdate, TestSolrCloudWithHadoopAuthPlugin, TestFieldCacheVsDocValues, DistribDocExpirationUpdateProcessorTest, TestDocBasedVersionConstraints, ZkCLITest, BadComponentTest, CollectionTooManyReplicasTest, ClusterStateUpdateTest, DeleteLastCustomShardedReplicaTest, LargeFieldTest, TestNumericRangeQuery64, RulesTest, ShardRoutingCustomTest, V2ApiIntegrationTest, TestSha256AuthenticationProvider, AnalysisAfterCoreReloadTest, LukeRequestHandlerTest, TestPartialUpdateDeduplication, TestCloudPseudoReturnFields, ReplaceNodeTest, TestInPlaceUpdatesDistrib, DirectoryFactoryTest, SpatialHeatmapFacetsTest, ZkControllerTest, TestSchemalessBufferedUpdates, TestFiltering, OverseerRolesTest, TestEmbeddedSolrServerSchemaAPI, DOMUtilTest, CurrencyFieldTypeTest, DistributedVersionInfoTest, TestSolrCLIRunExample, StatelessScriptUpdateProcessorFactoryTest, TestCollectionAPIs, TestStreamBody, HdfsTlogReplayBufferedWhileIndexingTest, TestRebalanceLeaders, SearchHandlerTest, ChaosMonkeySafeLeaderTest, TestSolrIndexConfig, HdfsChaosMonkeyNothingIsSafeTest, HdfsRestartWhileUpdatingTest, HdfsNNFailoverTest, TestGraphMLResponseWriter, TestSolrCloudSnapshots, ZkSolrClientTest, CSVRequestHandlerTest, TestCloudManagedSchema, TestStressInPlaceUpdates, TestSchemaNameResource, HighlighterTest, TestExactSharedStatsCache, SegmentsInfoRequestHandlerTest, TestHdfsBackupRestoreCore, GraphQueryTest, FileUtilsTest, TestSolrCloudWithKerberosAlt, ShardRoutingTest, DistanceFunctionTest, TestComplexPhraseLeadingWildcard, TestMaxScoreQueryParser, DistributedTermsComponentTest, TestPerFieldSimilarity, TolerantUpdateProcessorTest, TestNumericTerms32, CdcrBootstrapTest, DocValuesNotIndexedTest, LeaderElectionContextKeyTest]
[junit4] Completed [509/729 (1!)] on J1 in 12.42s, 1 test, 1 failure <<< FAILURES!
[...truncated 37221 lines...]