You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/11/04 16:54:40 UTC
[JENKINS] Lucene-Solr-Tests-master - Build # 2158 - Still Failing
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2158/
All tests passed
Build Log:
[...truncated 657 lines...]
[junit4] JVM J2: stdout was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/temp/junit4-J2-20171104_160038_2982733734348283433507.sysout
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] #
[junit4] # There is insufficient memory for the Java Runtime Environment to continue.
[junit4] # Native memory allocation (mmap) failed to map 524288 bytes for committing reserved memory.
[junit4] # An error report file with more information is saved as:
[junit4] # /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/J2/hs_err_pid6742.log
[junit4] <<< JVM J2: EOF ----
[junit4] JVM J2: stderr was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/temp/junit4-J2-20171104_160038_2981889974732820869921.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: INFO: os::commit_memory(0x00000000e4e00000, 524288, 0) failed; error='Cannot allocate memory' (errno=12)
[junit4] <<< JVM J2: EOF ----
[...truncated 1073 lines...]
[junit4] ERROR: JVM J2 ended with an exception, command line: /usr/local/asfpackages/java/jdk1.8.0_144/jre/bin/java -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/heapdumps -ea -esa -Dtests.prefix=tests -Dtests.seed=F61858E2FBB004D4 -Xmx512M -Dtests.iters= -Dtests.verbose=false -Dtests.infostream=false -Dtests.codec=random -Dtests.postingsformat=random -Dtests.docvaluesformat=random -Dtests.locale=random -Dtests.timezone=random -Dtests.directory=random -Dtests.linedocsfile=europarl.lines.txt.gz -Dtests.luceneMatchVersion=8.0.0 -Dtests.cleanthreads=perMethod -Djava.util.logging.config.file=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/tools/junit4/logging.properties -Dtests.nightly=false -Dtests.weekly=false -Dtests.monster=false -Dtests.slow=true -Dtests.asserts=true -Dtests.multiplier=2 -DtempDir=./temp -Djava.io.tmpdir=./temp -Djunit4.tempDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/temp -Dcommon.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene -Dclover.db.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/clover/db -Djava.security.policy=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/tools/junit4/tests.policy -Dtests.LUCENE_VERSION=8.0.0 -Djetty.testMode=1 -Djetty.insecurerandom=1 -Dsolr.directoryFactory=org.apache.solr.core.MockDirectoryFactory -Djava.awt.headless=true -Djdk.map.althashing.threshold=0 -Dtests.src.home=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master -Djava.security.egd=file:/dev/./urandom -Djunit4.childvm.cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/J2 -Djunit4.childvm.id=2 -Djunit4.childvm.count=3 -Dtests.leaveTemporary=false -Dtests.filterstacks=true -Djava.security.manager=org.apache.lucene.util.TestSecurityManager -Dfile.encoding=US-ASCII -classpath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/codecs/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/test-framework/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/test-framework/lib/junit-4.10.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/test-framework/lib/randomizedtesting-runner-2.5.3.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/classes/test:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-launcher.jar:/x1/jenkins/.ant/lib/ivy-2.4.0.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-junit.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-log4j.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-junit4.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jai.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-javamail.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-bsf.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-commons-net.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-antlr.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jsch.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-oro.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-commons-logging.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-netrexx.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-testutil.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jdepend.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-bcel.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-xalan2.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-resolver.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jmf.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-regexp.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-swing.jar:/usr/local/asfpackages/java/jdk1.8.0_144/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.5.3.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/temp/junit4-J2-20171104_160038_2987270772868946321190.events @/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/temp/junit4-J2-20171104_160038_298435081283252595675.suites -stdin
[junit4] ERROR: JVM J2 ended with an exception: Forked process returned with error code: 1. Very likely a JVM crash. See process stdout at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/temp/junit4-J2-20171104_160038_2982733734348283433507.sysout See process stderr at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/temp/junit4-J2-20171104_160038_2981889974732820869921.syserr
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1519)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:126)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:982)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:979)
[junit4] at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] at java.lang.Thread.run(Thread.java:748)
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:826: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:770: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build.xml:50: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/common-build.xml:1505: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/common-build.xml:1032: At least one slave process threw an exception, first: Forked process returned with error code: 1. Very likely a JVM crash. See process stdout at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/temp/junit4-J2-20171104_160038_2982733734348283433507.sysout See process stderr at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/temp/junit4-J2-20171104_160038_2981889974732820869921.syserr
Total time: 55 minutes 57 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[Fast Archiver] No artifacts from Lucene-Solr-Tests-master #2153 to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-Tests-master - Build # 2161 - Still Unstable
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2161/
11 tests failed.
FAILED: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test
Error Message:
Could not load collection from ZK: collection2
Stack Trace:
org.apache.solr.common.SolrException: Could not load collection from ZK: collection2
at __randomizedtesting.SeedInfo.seed([A7522407FDD2AB62:2F061BDD532EC69A]:0)
at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1172)
at org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:692)
at org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:130)
at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:154)
at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:140)
at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:135)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:908)
at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testIndexingBatchPerRequestWithHttpSolrClient(FullSolrCloudDistribCmdsTest.java:658)
at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test(FullSolrCloudDistribCmdsTest.java:152)
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:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:993)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/collection2/state.json
at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1212)
at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:332)
at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:332)
at org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1184)
at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1170)
... 49 more
FAILED: org.apache.solr.cloud.TestCryptoKeys.test
Error Message:
{ "responseHeader":{ "status":500, "QTime":31240}, "errorMessages":["4 out of 5 the property overlay to be of version 1 within 30 seconds! Failed cores: [https://127.0.0.1:46047/m/collection1_shard1_replica_n21/, https://127.0.0.1:44034/m/collection1_shard1_replica_n23/, https://127.0.0.1:39309/m/collection1_shard1_replica_n25/, https://127.0.0.1:52892/m/collection1_shard1_replica_n27/]\n"], "WARNING":"This response format is experimental. It is likely to change in the future.", "error":{ "metadata":[ "error-class","org.apache.solr.common.SolrException", "root-error-class","org.apache.solr.common.SolrException"], "msg":"4 out of 5 the property overlay to be of version 1 within 30 seconds! Failed cores: [https://127.0.0.1:46047/m/collection1_shard1_replica_n21/, https://127.0.0.1:44034/m/collection1_shard1_replica_n23/, https://127.0.0.1:39309/m/collection1_shard1_replica_n25/, https://127.0.0.1:52892/m/collection1_shard1_replica_n27/]", "trace":"org.apache.solr.common.SolrException: 4 out of 5 the property overlay to be of version 1 within 30 seconds! Failed cores: [https://127.0.0.1:46047/m/collection1_shard1_replica_n21/, https://127.0.0.1:44034/m/collection1_shard1_replica_n23/, https://127.0.0.1:39309/m/collection1_shard1_replica_n25/, https://127.0.0.1:52892/m/collection1_shard1_replica_n27/]\n\tat org.apache.solr.handler.SolrConfigHandler.waitForAllReplicasState(SolrConfigHandler.java:772)\n\tat org.apache.solr.handler.SolrConfigHandler.access$400(SolrConfigHandler.java:102)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handleCommands(SolrConfigHandler.java:503)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handlePOST(SolrConfigHandler.java:346)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.access$100(SolrConfigHandler.java:159)\n\tat org.apache.solr.handler.SolrConfigHandler.handleRequestBody(SolrConfigHandler.java:137)\n\tat org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)\n\tat org.apache.solr.core.SolrCore.execute(SolrCore.java:2484)\n\tat org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:710)\n\tat org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:516)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)\n\tat org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)\n\tat org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)\n\tat org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)\n\tat org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)\n\tat org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)\n\tat org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:426)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)\n\tat org.eclipse.jetty.server.Server.handle(Server.java:534)\n\tat org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)\n\tat org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)\n\tat org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)\n\tat org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:251)\n\tat org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)\n\tat org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)\n\tat org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)\n\tat org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)\n\tat org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)\n\tat java.lang.Thread.run(Thread.java:748)\n", "code":500}}
Stack Trace:
java.lang.AssertionError: {
"responseHeader":{
"status":500,
"QTime":31240},
"errorMessages":["4 out of 5 the property overlay to be of version 1 within 30 seconds! Failed cores: [https://127.0.0.1:46047/m/collection1_shard1_replica_n21/, https://127.0.0.1:44034/m/collection1_shard1_replica_n23/, https://127.0.0.1:39309/m/collection1_shard1_replica_n25/, https://127.0.0.1:52892/m/collection1_shard1_replica_n27/]\n"],
"WARNING":"This response format is experimental. It is likely to change in the future.",
"error":{
"metadata":[
"error-class","org.apache.solr.common.SolrException",
"root-error-class","org.apache.solr.common.SolrException"],
"msg":"4 out of 5 the property overlay to be of version 1 within 30 seconds! Failed cores: [https://127.0.0.1:46047/m/collection1_shard1_replica_n21/, https://127.0.0.1:44034/m/collection1_shard1_replica_n23/, https://127.0.0.1:39309/m/collection1_shard1_replica_n25/, https://127.0.0.1:52892/m/collection1_shard1_replica_n27/]",
"trace":"org.apache.solr.common.SolrException: 4 out of 5 the property overlay to be of version 1 within 30 seconds! Failed cores: [https://127.0.0.1:46047/m/collection1_shard1_replica_n21/, https://127.0.0.1:44034/m/collection1_shard1_replica_n23/, https://127.0.0.1:39309/m/collection1_shard1_replica_n25/, https://127.0.0.1:52892/m/collection1_shard1_replica_n27/]\n\tat org.apache.solr.handler.SolrConfigHandler.waitForAllReplicasState(SolrConfigHandler.java:772)\n\tat org.apache.solr.handler.SolrConfigHandler.access$400(SolrConfigHandler.java:102)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handleCommands(SolrConfigHandler.java:503)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handlePOST(SolrConfigHandler.java:346)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.access$100(SolrConfigHandler.java:159)\n\tat org.apache.solr.handler.SolrConfigHandler.handleRequestBody(SolrConfigHandler.java:137)\n\tat org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)\n\tat org.apache.solr.core.SolrCore.execute(SolrCore.java:2484)\n\tat org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:710)\n\tat org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:516)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)\n\tat org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)\n\tat org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)\n\tat org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)\n\tat org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)\n\tat org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)\n\tat org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:426)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)\n\tat org.eclipse.jetty.server.Server.handle(Server.java:534)\n\tat org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)\n\tat org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)\n\tat org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)\n\tat org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:251)\n\tat org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)\n\tat org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)\n\tat org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)\n\tat org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)\n\tat org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)\n\tat java.lang.Thread.run(Thread.java:748)\n",
"code":500}}
at __randomizedtesting.SeedInfo.seed([A7522407FDD2AB62:2F061BDD532EC69A]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.core.TestSolrConfigHandler.runConfigCommand(TestSolrConfigHandler.java:185)
at org.apache.solr.cloud.TestCryptoKeys.test(TestCryptoKeys.java:141)
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:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:993)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestRebalanceLeaders
Error Message:
104 threads leaked from SUITE scope at org.apache.solr.cloud.TestRebalanceLeaders: 1) Thread[id=9391, name=zkCallback-1817-thread-3-processing-n:127.0.0.1:50673_-EventThread, state=WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:501) 2) Thread[id=9366, name=zkCallback-1823-thread-4, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 3) Thread[id=9321, name=Thread-1695, state=WAITING, group=TGRP-TestRebalanceLeaders] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:502) at org.apache.solr.core.CloserThread.run(CoreContainer.java:1723) 4) Thread[id=9352, name=Scheduler-512937718, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 5) Thread[id=9404, name=Connection evictor, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66) at java.lang.Thread.run(Thread.java:748) 6) Thread[id=9342, name=Connection evictor, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66) at java.lang.Thread.run(Thread.java:748) 7) Thread[id=9358, name=zkCallback-1805-thread-2, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 8) Thread[id=9383, name=zkCallback-1811-thread-5, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 9) Thread[id=9249, name=qtp367539427-9249, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392) at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563) at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) 10) Thread[id=9415, name=searcherExecutor-3371-thread-1, state=WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 11) Thread[id=9291, name=zkCallback-1811-thread-1, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 12) Thread[id=9304, name=qtp462133376-9304, state=RUNNABLE, group=TGRP-TestRebalanceLeaders] at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method) at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269) at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101) at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:243) at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:191) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:249) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) at java.lang.Thread.run(Thread.java:748) 13) Thread[id=9279, name=qtp632970550-9279, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392) at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563) at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) 14) Thread[id=9394, name=zkCallback-1823-thread-2-processing-n:127.0.0.1:44736_-EventThread, state=WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:501) 15) Thread[id=9433, name=zkCallback-1817-thread-6, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 16) Thread[id=9261, name=zkCallback-1805-thread-1, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 17) Thread[id=9337, name=qtp1090265208-9337, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392) at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563) at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) 18) Thread[id=9411, name=searcherExecutor-3365-thread-1, state=WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 19) Thread[id=9375, name=zkCallback-1805-thread-2-processing-n:127.0.0.1:51414_-EventThread, state=WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:501) 20) Thread[id=9432, name=zkCallback-1805-thread-7, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 21) Thread[id=9298, name=searcherExecutor-3340-thread-1, state=WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 22) Thread[id=9374, name=zkCallback-1805-thread-2-processing-n:127.0.0.1:51414_-SendThread(127.0.0.1:59219), state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101) at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:997) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1060) 23) Thread[id=9262, name=Thread-1677, state=WAITING, group=TGRP-TestRebalanceLeaders] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:502) at org.apache.solr.core.CloserThread.run(CoreContainer.java:1723) 24) Thread[id=9343, name=Connection evictor, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66) at java.lang.Thread.run(Thread.java:748) 25) Thread[id=9393, name=zkCallback-1823-thread-2-processing-n:127.0.0.1:44736_-SendThread(127.0.0.1:59219), state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101) at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:997) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1060) 26) Thread[id=9335, name=qtp1090265208-9335, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392) at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563) at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) 27) Thread[id=9300, name=Connection evictor, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66) at java.lang.Thread.run(Thread.java:748) 28) Thread[id=9390, name=zkCallback-1817-thread-3-processing-n:127.0.0.1:50673_-SendThread(127.0.0.1:59219), state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101) at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:997) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1060) 29) Thread[id=9284, name=Connection evictor, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66) at java.lang.Thread.run(Thread.java:748) 30) Thread[id=9395, name=OverseerStateUpdate-98950704354164756-127.0.0.1:51414_-n_0000000005, state=TIMED_WAITING, group=Overseer state updater.] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.apache.solr.cloud.ZkDistributedQueue.peekElements(ZkDistributedQueue.java:463) at org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:203) at java.lang.Thread.run(Thread.java:748) 31) Thread[id=9248, name=qtp367539427-9248, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392) at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563) at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) 32) Thread[id=9361, name=zkCallback-1817-thread-2, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 33) Thread[id=9362, name=zkCallback-1817-thread-3, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 34) Thread[id=9430, name=zkCallback-1823-thread-6, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 35) Thread[id=9276, name=qtp632970550-9276, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392) at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563) at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) 36) Thread[id=9242, name=qtp367539427-9242, state=RUNNABLE, group=TGRP-TestRebalanceLeaders] at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method) at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269) at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101) at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:243) at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:191) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:249) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) at java.lang.Thread.run(Thread.java:748) 37) Thread[id=9384, name=zkCallback-1811-thread-6, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 38) Thread[id=9408, name=OverseerThreadFactory-3366-thread-1, state=WAITING, group=Overseer collection creation process.] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:458) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.take(SynchronousQueue.java:924) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 39) Thread[id=9369, name=zkCallback-1811-thread-4, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 40) Thread[id=9336, name=qtp1090265208-9336, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392) at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563) at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) 41) Thread[id=9392, name=zkCallback-1817-thread-5, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 42) Thread[id=9275, name=qtp632970550-9275, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392) at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563) at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) 43) Thread[id=9308, name=qtp462133376-9308, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392) at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563) at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) 44) Thread[id=9255, name=Connection evictor, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66) at java.lang.Thread.run(Thread.java:748) 45) Thread[id=9349, name=zkCallback-1823-thread-1, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 46) Thread[id=9367, name=zkCallback-1811-thread-2, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 47) Thread[id=9385, name=zkCallback-1811-thread-3-processing-n:127.0.0.1:35123_-SendThread(127.0.0.1:59219), state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1051) 48) Thread[id=9431, name=zkCallback-1811-thread-7, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 49) Thread[id=9274, name=qtp632970550-9274-acceptor-0@6b62299a-ServerConnector@1b583f02{SSL,[ssl, http/1.1]}{127.0.0.1:35123}, state=RUNNABLE, group=TGRP-TestRebalanceLeaders] at sun.nio.ch.ServerSocketChannelImpl.accept0(Native Method) at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:422) at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:250) at org.eclipse.jetty.server.ServerConnector.accept(ServerConnector.java:371) at org.eclipse.jetty.server.AbstractConnector$Acceptor.run(AbstractConnector.java:601) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) at java.lang.Thread.run(Thread.java:748) 50) Thread[id=9365, name=zkCallback-1823-thread-3, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 51) Thread[id=9272, name=qtp632970550-9272, state=RUNNABLE, group=TGRP-TestRebalanceLeaders] at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method) at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269) at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101) at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:243) at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:191) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:249) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) at java.lang.Thread.run(Thread.java:748) 52) Thread[id=9350, name=Thread-1704, state=WAITING, group=TGRP-TestRebalanceLeaders] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:502) at org.apache.solr.core.CloserThread.run(CoreContainer.java:1723) 53) Thread[id=9373, name=zkCallback-1805-thread-6, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 54) Thread[id=9245, name=qtp367539427-9245, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392) at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563) at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) 55) Thread[id=9324, name=Scheduler-785887505, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 56) Thread[id=9266, name=Scheduler-2008968845, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 57) Thread[id=9277, name=qtp632970550-9277, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392) at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563) at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) 58) Thread[id=9243, name=qtp367539427-9243, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392) at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563) at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) 59) Thread[id=9429, name=AutoscalingActionExecutor-3364-thread-1, state=WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 60) Thread[id=9247, name=qtp367539427-9247, state=RUNNABLE, group=TGRP-TestRebalanceLeaders] at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method) at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269) at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101) at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:243) at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:191) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:249) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) at java.lang.Thread.run(Thread.java:748) 61) Thread[id=9244, name=qtp367539427-9244-acceptor-0@689af493-ServerConnector@18a937a0{SSL,[ssl, http/1.1]}{127.0.0.1:51414}, state=RUNNABLE, group=TGRP-TestRebalanceLeaders] at sun.nio.ch.ServerSocketChannelImpl.accept0(Native Method) at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:422) at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:250) at org.eclipse.jetty.server.ServerConnector.accept(ServerConnector.java:371) at org.eclipse.jetty.server.AbstractConnector$Acceptor.run(AbstractConnector.java:601) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) at java.lang.Thread.run(Thread.java:748) 62) Thread[id=9285, name=Connection evictor, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66) at java.lang.Thread.run(Thread.java:748) 63) Thread[id=9360, name=zkCallback-1805-thread-4, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 64) Thread[id=9303, name=qtp462133376-9303-acceptor-0@2c9e97bf-ServerConnector@552bfc18{SSL,[ssl, http/1.1]}{127.0.0.1:50673}, state=RUNNABLE, group=TGRP-TestRebalanceLeaders] at sun.nio.ch.ServerSocketChannelImpl.accept0(Native Method) at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:422) at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:250) at org.eclipse.jetty.server.ServerConnector.accept(ServerConnector.java:371) at org.eclipse.jetty.server.AbstractConnector$Acceptor.run(AbstractConnector.java:601) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) at java.lang.Thread.run(Thread.java:748) 65) Thread[id=9327, name=searcherExecutor-3351-thread-1, state=WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 66) Thread[id=9305, name=qtp462133376-9305, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392) at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563) at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) 67) Thread[id=9273, name=qtp632970550-9273, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392) at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563) at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) 68) Thread[id=9250, name=org.eclipse.jetty.server.session.HashSessionManager@2abefef3Timer, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 69) Thread[id=9280, name=org.eclipse.jetty.server.session.HashSessionManager@7d7486b4Timer, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 70) Thread[id=9412, name=ScheduledTrigger-3363-thread-3, state=WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1088) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 71) Thread[id=9330, name=qtp1090265208-9330, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392) at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563) at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) 72) Thread[id=9372, name=zkCallback-1805-thread-5, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 73) Thread[id=9278, name=qtp632970550-9278, state=RUNNABLE, group=TGRP-TestRebalanceLeaders] at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method) at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269) at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101) at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:243) at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:191) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:249) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) at java.lang.Thread.run(Thread.java:748) 74) Thread[id=9364, name=zkCallback-1823-thread-2, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 75) Thread[id=9314, name=Connection evictor, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66) at java.lang.Thread.run(Thread.java:748) 76) Thread[id=9320, name=zkCallback-1817-thread-1, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 77) Thread[id=9254, name=Connection evictor, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66) at java.lang.Thread.run(Thread.java:748) 78) Thread[id=9295, name=Scheduler-862393933, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 79) Thread[id=9271, name=Connection evictor, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66) at java.lang.Thread.run(Thread.java:748) 80) Thread[id=9380, name=zkCallback-1823-thread-5, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 81) Thread[id=9302, name=qtp462133376-9302, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392) at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563) at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) 82) Thread[id=9368, name=zkCallback-1811-thread-3, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 83) Thread[id=9332, name=qtp1090265208-9332-acceptor-0@2df650ec-ServerConnector@55aeec87{SSL,[ssl, http/1.1]}{127.0.0.1:44736}, state=RUNNABLE, group=TGRP-TestRebalanceLeaders] at sun.nio.ch.ServerSocketChannelImpl.accept0(Native Method) at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:422) at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:250) at org.eclipse.jetty.server.ServerConnector.accept(ServerConnector.java:371) at org.eclipse.jetty.server.AbstractConnector$Acceptor.run(AbstractConnector.java:601) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) at java.lang.Thread.run(Thread.java:748) 84) Thread[id=9359, name=zkCallback-1805-thread-3, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 85) Thread[id=9338, name=org.eclipse.jetty.server.session.HashSessionManager@37d0fb17Timer, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 86) Thread[id=9413, name=ScheduledTrigger-3363-thread-4, state=WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1088) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 87) Thread[id=9301, name=qtp462133376-9301, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392) at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563) at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) 88) Thread[id=9292, name=Thread-1686, state=WAITING, group=TGRP-TestRebalanceLeaders] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:502) at org.apache.solr.core.CloserThread.run(CoreContainer.java:1723) 89) Thread[id=9407, name=ScheduledTrigger-3363-thread-1, state=WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1088) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 90) Thread[id=9246, name=qtp367539427-9246, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392) at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563) at org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) 91) Thread[id=9363, name=zkCallback-1817-thread-4, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 92) Thread[id=9386, name=zkCallback-1811-thread-3-processing-n:127.0.0.1:35123_-EventThread, state=WAITING, group=TGRP-TestRebalanceLeaders] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:501) 93) Thread[id=9333, name=qtp1090265208-9333, state=RUNNABLE, group=TGRP-TestRebalanceLeaders] at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method) at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269) at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101) at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:243) at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:191) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:249) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) at java.lang.Thread.run(Thread.java:748) 94) Thread[id=9
[...truncated too long message...]
lExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 53) Thread[id=9392, name=zkCallback-1817-thread-5, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
[junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)
[junit4] > at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 54) Thread[id=9409, name=ScheduledTrigger-3363-thread-2, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 55) Thread[id=9309, name=org.eclipse.jetty.server.session.HashSessionManager@543e327Timer, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 56) Thread[id=9306, name=qtp462133376-9306, state=RUNNABLE, group=TGRP-TestRebalanceLeaders]
[junit4] > at sun.nio.ch.EPollArrayWrapper.interrupt(Native Method)
[junit4] > at sun.nio.ch.EPollArrayWrapper.interrupt(EPollArrayWrapper.java:317)
[junit4] > at sun.nio.ch.EPollSelectorImpl.wakeup(EPollSelectorImpl.java:207)
[junit4] > at java.nio.channels.spi.AbstractSelector$1.interrupt(AbstractSelector.java:213)
[junit4] > at java.nio.channels.spi.AbstractSelector.begin(AbstractSelector.java:219)
[junit4] > at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:92)
[junit4] > at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
[junit4] > at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
[junit4] > at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
[junit4] > at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:243)
[junit4] > at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:191)
[junit4] > at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:249)
[junit4] > at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] > at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 57) Thread[id=9334, name=qtp1090265208-9334, state=RUNNABLE, group=TGRP-TestRebalanceLeaders]
[junit4] > at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
[junit4] > at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
[junit4] > at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
[junit4] > at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
[junit4] > at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
[junit4] > at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
[junit4] > at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:243)
[junit4] > at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:191)
[junit4] > at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:249)
[junit4] > at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] > at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 58) Thread[id=9269, name=searcherExecutor-3329-thread-1, state=WAITING, group=TGRP-TestRebalanceLeaders]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 59) Thread[id=9349, name=zkCallback-1823-thread-1, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
[junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)
[junit4] > at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 60) Thread[id=9367, name=zkCallback-1811-thread-2, state=TIMED_WAITING, group=TGRP-TestRebalanceLeaders]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
[junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)
[junit4] > at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at __randomizedtesting.SeedInfo.seed([A7522407FDD2AB62]:0)
[junit4] Completed [238/749 (8!)] on J1 in 158.56s, 1 test, 3 errors <<< FAILURES!
[...truncated 48836 lines...]
[JENKINS] Lucene-Solr-Tests-master - Build # 2160 - Still unstable
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2160/
3 tests failed.
FAILED: org.apache.solr.cloud.ZkFailoverTest.testRestartZkWhenClusterDown
Error Message:
Timeout waiting for number of live nodes = 2
Stack Trace:
java.lang.AssertionError: Timeout waiting for number of live nodes = 2
at __randomizedtesting.SeedInfo.seed([13F2C729CFF60E7D:7D654465B611A8FE]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.ZkFailoverTest.waitForLiveNodes(ZkFailoverTest.java:88)
at org.apache.solr.cloud.ZkFailoverTest.testRestartZkWhenClusterDown(ZkFailoverTest.java:74)
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:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ZkFailoverTest
Error Message:
4 threads leaked from SUITE scope at org.apache.solr.cloud.ZkFailoverTest: 1) Thread[id=11011, name=ProcessThread(sid:0 cport:36451):, state=WAITING, group=TGRP-ZkFailoverTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at org.apache.zookeeper.server.PrepRequestProcessor.run(PrepRequestProcessor.java:122) 2) Thread[id=11007, name=Thread-2005, state=WAITING, group=TGRP-ZkFailoverTest] at java.lang.Object.wait(Native Method) at java.lang.Thread.join(Thread.java:1252) at java.lang.Thread.join(Thread.java:1326) at org.apache.zookeeper.server.NIOServerCnxnFactory.join(NIOServerCnxnFactory.java:297) at org.apache.solr.cloud.ZkTestServer$ZKServerMain.runFromConfig(ZkTestServer.java:309) at org.apache.solr.cloud.ZkTestServer$2.run(ZkTestServer.java:490) 3) Thread[id=11009, name=SessionTracker, state=TIMED_WAITING, group=TGRP-ZkFailoverTest] at java.lang.Object.wait(Native Method) at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:146) 4) Thread[id=11008, name=NIOServerCxn.Factory:0.0.0.0/0.0.0.0:36451, state=RUNNABLE, group=TGRP-ZkFailoverTest] at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method) at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269) at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:173) at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 4 threads leaked from SUITE scope at org.apache.solr.cloud.ZkFailoverTest:
1) Thread[id=11011, name=ProcessThread(sid:0 cport:36451):, state=WAITING, group=TGRP-ZkFailoverTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.zookeeper.server.PrepRequestProcessor.run(PrepRequestProcessor.java:122)
2) Thread[id=11007, name=Thread-2005, state=WAITING, group=TGRP-ZkFailoverTest]
at java.lang.Object.wait(Native Method)
at java.lang.Thread.join(Thread.java:1252)
at java.lang.Thread.join(Thread.java:1326)
at org.apache.zookeeper.server.NIOServerCnxnFactory.join(NIOServerCnxnFactory.java:297)
at org.apache.solr.cloud.ZkTestServer$ZKServerMain.runFromConfig(ZkTestServer.java:309)
at org.apache.solr.cloud.ZkTestServer$2.run(ZkTestServer.java:490)
3) Thread[id=11009, name=SessionTracker, state=TIMED_WAITING, group=TGRP-ZkFailoverTest]
at java.lang.Object.wait(Native Method)
at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:146)
4) Thread[id=11008, name=NIOServerCxn.Factory:0.0.0.0/0.0.0.0:36451, state=RUNNABLE, group=TGRP-ZkFailoverTest]
at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:173)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([13F2C729CFF60E7D]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ZkFailoverTest
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=11008, name=NIOServerCxn.Factory:0.0.0.0/0.0.0.0:36451, state=RUNNABLE, group=TGRP-ZkFailoverTest] at sun.nio.ch.IOUtil.drain(Native Method) at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:104) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:173) at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=11008, name=NIOServerCxn.Factory:0.0.0.0/0.0.0.0:36451, state=RUNNABLE, group=TGRP-ZkFailoverTest]
at sun.nio.ch.IOUtil.drain(Native Method)
at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:104)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:173)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([13F2C729CFF60E7D]:0)
Build Log:
[...truncated 12315 lines...]
[junit4] Suite: org.apache.solr.cloud.ZkFailoverTest
[junit4] 2> 2463047 INFO (SUITE-ZkFailoverTest-seed#[13F2C729CFF60E7D]-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/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/init-core-data-001
[junit4] 2> 2463048 WARN (SUITE-ZkFailoverTest-seed#[13F2C729CFF60E7D]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=6 numCloses=6
[junit4] 2> 2463048 INFO (SUITE-ZkFailoverTest-seed#[13F2C729CFF60E7D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2463082 INFO (SUITE-ZkFailoverTest-seed#[13F2C729CFF60E7D]-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> 2463102 INFO (SUITE-ZkFailoverTest-seed#[13F2C729CFF60E7D]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001
[junit4] 2> 2463102 INFO (SUITE-ZkFailoverTest-seed#[13F2C729CFF60E7D]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2463102 INFO (Thread-1958) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2463102 INFO (Thread-1958) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2463119 ERROR (Thread-1958) [ ] 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> 2463211 INFO (SUITE-ZkFailoverTest-seed#[13F2C729CFF60E7D]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:36451
[junit4] 2> 2463486 INFO (jetty-launcher-2823-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 2463619 INFO (jetty-launcher-2823-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 2463747 INFO (jetty-launcher-2823-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3f2f6e5d{/solr,null,AVAILABLE}
[junit4] 2> 2463749 INFO (jetty-launcher-2823-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@26b093f9{SSL,[ssl, http/1.1]}{127.0.0.1:43829}
[junit4] 2> 2463749 INFO (jetty-launcher-2823-thread-2) [ ] o.e.j.s.Server Started @2479772ms
[junit4] 2> 2463749 INFO (jetty-launcher-2823-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=43829}
[junit4] 2> 2463749 ERROR (jetty-launcher-2823-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2463767 INFO (jetty-launcher-2823-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 2463767 INFO (jetty-launcher-2823-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2463767 INFO (jetty-launcher-2823-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2463767 INFO (jetty-launcher-2823-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-11-04T21:38:01.539Z
[junit4] 2> 2463813 INFO (jetty-launcher-2823-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5eed3ebc{/solr,null,AVAILABLE}
[junit4] 2> 2463813 INFO (jetty-launcher-2823-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@344584b8{SSL,[ssl, http/1.1]}{127.0.0.1:34842}
[junit4] 2> 2463813 INFO (jetty-launcher-2823-thread-1) [ ] o.e.j.s.Server Started @2479836ms
[junit4] 2> 2463813 INFO (jetty-launcher-2823-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34842}
[junit4] 2> 2463813 ERROR (jetty-launcher-2823-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2463813 INFO (jetty-launcher-2823-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 2463814 INFO (jetty-launcher-2823-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2463814 INFO (jetty-launcher-2823-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2463814 INFO (jetty-launcher-2823-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-11-04T21:38:01.586Z
[junit4] 2> 2463833 INFO (jetty-launcher-2823-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2463983 INFO (jetty-launcher-2823-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36451/solr
[junit4] 2> 2464271 INFO (jetty-launcher-2823-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2464389 INFO (jetty-launcher-2823-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36451/solr
[junit4] 2> 2464901 INFO (jetty-launcher-2823-thread-2) [n:127.0.0.1:43829_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2464965 INFO (jetty-launcher-2823-thread-2) [n:127.0.0.1:43829_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:43829_solr
[junit4] 2> 2464966 INFO (jetty-launcher-2823-thread-2) [n:127.0.0.1:43829_solr ] o.a.s.c.Overseer Overseer (id=98948315931607046-127.0.0.1:43829_solr-n_0000000000) starting
[junit4] 2> 2464982 INFO (jetty-launcher-2823-thread-2) [n:127.0.0.1:43829_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43829_solr
[junit4] 2> 2465140 INFO (OverseerStateUpdate-98948315931607046-127.0.0.1:43829_solr-n_0000000000) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2465556 INFO (jetty-launcher-2823-thread-1) [n:127.0.0.1:34842_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2465557 INFO (jetty-launcher-2823-thread-1) [n:127.0.0.1:34842_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2465558 INFO (jetty-launcher-2823-thread-1) [n:127.0.0.1:34842_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34842_solr
[junit4] 2> 2465612 INFO (jetty-launcher-2823-thread-2) [n:127.0.0.1:43829_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43829.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@95687d1
[junit4] 2> 2465697 INFO (zkCallback-2835-thread-1-processing-n:127.0.0.1:34842_solr) [n:127.0.0.1:34842_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2465701 INFO (zkCallback-2831-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2465787 INFO (jetty-launcher-2823-thread-2) [n:127.0.0.1:43829_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43829.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@95687d1
[junit4] 2> 2465787 INFO (jetty-launcher-2823-thread-2) [n:127.0.0.1:43829_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43829.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@95687d1
[junit4] 2> 2465788 INFO (jetty-launcher-2823-thread-2) [n:127.0.0.1:43829_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node2/.
[junit4] 2> 2466054 INFO (jetty-launcher-2823-thread-1) [n:127.0.0.1:34842_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34842.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@95687d1
[junit4] 2> 2466108 INFO (jetty-launcher-2823-thread-1) [n:127.0.0.1:34842_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34842.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@95687d1
[junit4] 2> 2466108 INFO (jetty-launcher-2823-thread-1) [n:127.0.0.1:34842_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34842.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@95687d1
[junit4] 2> 2466109 INFO (jetty-launcher-2823-thread-1) [n:127.0.0.1:34842_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node1/.
[junit4] 2> 2466215 INFO (SUITE-ZkFailoverTest-seed#[13F2C729CFF60E7D]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2466215 INFO (SUITE-ZkFailoverTest-seed#[13F2C729CFF60E7D]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36451/solr ready
[junit4] 2> 2467251 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRestartZkWhenClusterDown
[junit4] 2> 2467693 INFO (qtp1393580694-10888) [n:127.0.0.1:43829_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&replicationFactor=1&name=coll1&nrtReplicas=1&action=CREATE&numShards=2&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2467753 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.CreateCollectionCmd Create collection coll1
[junit4] 2> 2467773 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.CreateCollectionCmd Only _default config set found, using it.
[junit4] 2> 2467810 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/coll1/protwords.txt
[junit4] 2> 2467865 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/coll1/managed-schema
[junit4] 2> 2467936 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/coll1/solrconfig.xml
[junit4] 2> 2467959 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/coll1/synonyms.txt
[junit4] 2> 2467960 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/coll1/stopwords.txt
[junit4] 2> 2468021 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/coll1/params.json
[junit4] 2> 2468022 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/coll1/lang/contractions_it.txt
[junit4] 2> 2468023 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/coll1/lang/stopwords_no.txt
[junit4] 2> 2468024 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/coll1/lang/stopwords_hy.txt
[junit4] 2> 2468024 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/coll1/lang/stemdict_nl.txt
[junit4] 2> 2468025 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/coll1/lang/contractions_ca.txt
[junit4] 2> 2468026 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/coll1/lang/stopwords_id.txt
[junit4] 2> 2468027 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/coll1/lang/stopwords_ga.txt
[junit4] 2> 2468027 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/coll1/lang/stopwords_da.txt
[junit4] 2> 2468028 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/coll1/lang/stopwords_hi.txt
[junit4] 2> 2468029 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/coll1/lang/stopwords_ja.txt
[junit4] 2> 2468050 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/coll1/lang/stopwords_pt.txt
[junit4] 2> 2468051 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/coll1/lang/stopwords_hu.txt
[junit4] 2> 2468052 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/coll1/lang/stopwords_el.txt
[junit4] 2> 2468052 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/coll1/lang/stopwords_ru.txt
[junit4] 2> 2468053 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/coll1/lang/stopwords_tr.txt
[junit4] 2> 2468087 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/coll1/lang/stopwords_ar.txt
[junit4] 2> 2468087 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/coll1/lang/userdict_ja.txt
[junit4] 2> 2468088 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/coll1/lang/stopwords_it.txt
[junit4] 2> 2468089 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/coll1/lang/stopwords_eu.txt
[junit4] 2> 2468090 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/coll1/lang/stopwords_cz.txt
[junit4] 2> 2468104 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/coll1/lang/stoptags_ja.txt
[junit4] 2> 2468105 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/coll1/lang/contractions_fr.txt
[junit4] 2> 2468106 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/coll1/lang/stopwords_fa.txt
[junit4] 2> 2468139 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/coll1/lang/stopwords_de.txt
[junit4] 2> 2468140 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/coll1/lang/contractions_ga.txt
[junit4] 2> 2468140 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/coll1/lang/stopwords_ca.txt
[junit4] 2> 2468141 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/coll1/lang/stopwords_nl.txt
[junit4] 2> 2468142 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/coll1/lang/stopwords_bg.txt
[junit4] 2> 2468142 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/coll1/lang/stopwords_es.txt
[junit4] 2> 2468159 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/coll1/lang/stopwords_en.txt
[junit4] 2> 2468160 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/coll1/lang/stopwords_lv.txt
[junit4] 2> 2468161 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/coll1/lang/stopwords_th.txt
[junit4] 2> 2468162 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/coll1/lang/stopwords_ro.txt
[junit4] 2> 2468162 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/coll1/lang/stopwords_fr.txt
[junit4] 2> 2468163 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/coll1/lang/stopwords_sv.txt
[junit4] 2> 2468177 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/coll1/lang/stopwords_fi.txt
[junit4] 2> 2468177 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/coll1/lang/hyphenations_ga.txt
[junit4] 2> 2468178 INFO (OverseerThreadFactory-3275-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/coll1/lang/stopwords_gl.txt
[junit4] 2> 2468299 INFO (OverseerStateUpdate-98948315931607046-127.0.0.1:43829_solr-n_0000000000) [n:127.0.0.1:43829_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"coll1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"coll1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:43829/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2468301 INFO (OverseerStateUpdate-98948315931607046-127.0.0.1:43829_solr-n_0000000000) [n:127.0.0.1:43829_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"coll1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"coll1_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:34842/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2468788 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=coll1&newCollection=true&name=coll1_shard1_replica_n1&action=CREATE&numShards=2&collection=coll1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2468789 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2468922 INFO (zkCallback-2831-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2468923 INFO (zkCallback-2831-thread-2-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2469349 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=coll1&newCollection=true&name=coll1_shard2_replica_n2&action=CREATE&numShards=2&collection=coll1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2469350 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2469461 INFO (zkCallback-2831-thread-2-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2469461 INFO (zkCallback-2831-thread-3-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2469475 INFO (zkCallback-2835-thread-1-processing-n:127.0.0.1:34842_solr) [n:127.0.0.1:34842_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2469475 INFO (zkCallback-2835-thread-2-processing-n:127.0.0.1:34842_solr) [n:127.0.0.1:34842_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2470040 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 2470040 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 2470040 WARN (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node2/coll1_shard1_replica_n1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node2/coll1_shard1_replica_n1/../../../../contrib/extraction/lib
[junit4] 2> 2470041 WARN (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node2/coll1_shard1_replica_n1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node2/coll1_shard1_replica_n1/../../../../dist
[junit4] 2> 2470041 WARN (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node2/coll1_shard1_replica_n1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node2/coll1_shard1_replica_n1/../../../../contrib/clustering/lib
[junit4] 2> 2470041 WARN (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node2/coll1_shard1_replica_n1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node2/coll1_shard1_replica_n1/../../../../dist
[junit4] 2> 2470041 WARN (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node2/coll1_shard1_replica_n1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node2/coll1_shard1_replica_n1/../../../../contrib/langid/lib
[junit4] 2> 2470041 WARN (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node2/coll1_shard1_replica_n1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node2/coll1_shard1_replica_n1/../../../../dist
[junit4] 2> 2470041 WARN (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node2/coll1_shard1_replica_n1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node2/coll1_shard1_replica_n1/../../../../contrib/velocity/lib
[junit4] 2> 2470041 WARN (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node2/coll1_shard1_replica_n1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node2/coll1_shard1_replica_n1/../../../../dist
[junit4] 2> 2470097 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2470187 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.s.IndexSchema [coll1_shard1_replica_n1] Schema name=default-config
[junit4] 2> 2470506 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 2470506 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 2470507 WARN (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node1/coll1_shard2_replica_n2/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node1/coll1_shard2_replica_n2/../../../../contrib/extraction/lib
[junit4] 2> 2470582 WARN (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node1/coll1_shard2_replica_n2/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node1/coll1_shard2_replica_n2/../../../../dist
[junit4] 2> 2470582 WARN (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node1/coll1_shard2_replica_n2/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node1/coll1_shard2_replica_n2/../../../../contrib/clustering/lib
[junit4] 2> 2470582 WARN (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node1/coll1_shard2_replica_n2/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node1/coll1_shard2_replica_n2/../../../../dist
[junit4] 2> 2470583 WARN (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node1/coll1_shard2_replica_n2/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node1/coll1_shard2_replica_n2/../../../../contrib/langid/lib
[junit4] 2> 2470583 WARN (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node1/coll1_shard2_replica_n2/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node1/coll1_shard2_replica_n2/../../../../dist
[junit4] 2> 2470583 WARN (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node1/coll1_shard2_replica_n2/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node1/coll1_shard2_replica_n2/../../../../contrib/velocity/lib
[junit4] 2> 2470583 WARN (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node1/coll1_shard2_replica_n2/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node1/coll1_shard2_replica_n2/../../../../dist
[junit4] 2> 2470633 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2470716 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.s.IndexSchema [coll1_shard2_replica_n2] Schema name=default-config
[junit4] 2> 2471790 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 2471869 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 2471980 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'coll1_shard2_replica_n2' using configuration from collection coll1, trusted=true
[junit4] 2> 2471981 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34842.solr.core.coll1.shard2.replica_n2' (registry 'solr.core.coll1.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@95687d1
[junit4] 2> 2471981 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2471981 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrCore [[coll1_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node1/coll1_shard2_replica_n2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node1/./coll1_shard2_replica_n2/data/]
[junit4] 2> 2472047 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 2472379 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'coll1_shard1_replica_n1' using configuration from collection coll1, trusted=true
[junit4] 2> 2472650 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43829.solr.core.coll1.shard1.replica_n1' (registry 'solr.core.coll1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@95687d1
[junit4] 2> 2472650 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2472650 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrCore [[coll1_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node2/coll1_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ZkFailoverTest_13F2C729CFF60E7D-001/tempDir-001/node2/./coll1_shard1_replica_n1/data/]
[junit4] 2> 2472706 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2472706 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2472722 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 2472722 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2472724 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@25baaec[coll1_shard2_replica_n2] main]
[junit4] 2> 2472724 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/coll1
[junit4] 2> 2472725 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/coll1
[junit4] 2> 2472725 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/coll1/managed-schema
[junit4] 2> 2472725 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 2472758 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2472758 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 2472760 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2472760 INFO (searcherExecutor-3280-thread-1-processing-n:127.0.0.1:34842_solr x:coll1_shard2_replica_n2 s:shard2 c:coll1 r:core_node4) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@25baaec[coll1_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2472760 INFO (searcherExecutor-3280-thread-1-processing-n:127.0.0.1:34842_solr x:coll1_shard2_replica_n2 s:shard2 c:coll1 r:core_node4) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 2472760 INFO (searcherExecutor-3280-thread-1-processing-n:127.0.0.1:34842_solr x:coll1_shard2_replica_n2 s:shard2 c:coll1 r:core_node4) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2472761 INFO (searcherExecutor-3280-thread-1-processing-n:127.0.0.1:34842_solr x:coll1_shard2_replica_n2 s:shard2 c:coll1 r:core_node4) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrCore [coll1_shard2_replica_n2] Registered new searcher Searcher@25baaec[coll1_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2472761 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1583173065017131008
[junit4] 2> 2472795 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 2472798 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2472798 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2472798 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:34842/solr/coll1_shard2_replica_n2/
[junit4] 2> 2472799 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2472799 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SyncStrategy https://127.0.0.1:34842/solr/coll1_shard2_replica_n2/ has no replicas
[junit4] 2> 2472831 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 2472833 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:34842/solr/coll1_shard2_replica_n2/ shard2
[junit4] 2> 2472941 INFO (zkCallback-2835-thread-2-processing-n:127.0.0.1:34842_solr) [n:127.0.0.1:34842_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2472941 INFO (zkCallback-2835-thread-1-processing-n:127.0.0.1:34842_solr) [n:127.0.0.1:34842_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2472953 INFO (zkCallback-2831-thread-3-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2472953 INFO (zkCallback-2831-thread-2-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2472998 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2473015 INFO (qtp325177225-10896) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=coll1&newCollection=true&name=coll1_shard2_replica_n2&action=CREATE&numShards=2&collection=coll1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=3665
[junit4] 2> 2473193 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2473193 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2473194 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 2473194 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2473218 INFO (zkCallback-2835-thread-1-processing-n:127.0.0.1:34842_solr) [n:127.0.0.1:34842_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2473218 INFO (zkCallback-2835-thread-2-processing-n:127.0.0.1:34842_solr) [n:127.0.0.1:34842_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2473237 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6674e968[coll1_shard1_replica_n1] main]
[junit4] 2> 2473238 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/coll1
[junit4] 2> 2473239 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/coll1
[junit4] 2> 2473239 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/coll1/managed-schema
[junit4] 2> 2473239 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 2473240 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2473240 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 2473241 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2473242 INFO (searcherExecutor-3282-thread-1-processing-n:127.0.0.1:43829_solr x:coll1_shard1_replica_n1 s:shard1 c:coll1 r:core_node3) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@6674e968[coll1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2473242 INFO (searcherExecutor-3282-thread-1-processing-n:127.0.0.1:43829_solr x:coll1_shard1_replica_n1 s:shard1 c:coll1 r:core_node3) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 2473242 INFO (searcherExecutor-3282-thread-1-processing-n:127.0.0.1:43829_solr x:coll1_shard1_replica_n1 s:shard1 c:coll1 r:core_node3) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2473266 INFO (zkCallback-2831-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2473266 INFO (zkCallback-2831-thread-2-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2473284 INFO (searcherExecutor-3282-thread-1-processing-n:127.0.0.1:43829_solr x:coll1_shard1_replica_n1 s:shard1 c:coll1 r:core_node3) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrCore [coll1_shard1_replica_n1] Registered new searcher Searcher@6674e968[coll1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2473284 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1583173065565536256
[junit4] 2> 2473299 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2473299 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2473299 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:43829/solr/coll1_shard1_replica_n1/
[junit4] 2> 2473299 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2473299 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:43829/solr/coll1_shard1_replica_n1/ has no replicas
[junit4] 2> 2473299 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 2473301 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:43829/solr/coll1_shard1_replica_n1/ shard1
[junit4] 2> 2473430 INFO (zkCallback-2831-thread-2-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2473430 INFO (zkCallback-2831-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2473433 INFO (zkCallback-2835-thread-1-processing-n:127.0.0.1:34842_solr) [n:127.0.0.1:34842_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2473433 INFO (zkCallback-2835-thread-2-processing-n:127.0.0.1:34842_solr) [n:127.0.0.1:34842_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2473466 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2473467 INFO (qtp1393580694-10885) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=coll1&newCollection=true&name=coll1_shard1_replica_n1&action=CREATE&numShards=2&collection=coll1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=4678
[junit4] 2> 2473617 INFO (qtp1393580694-10888) [n:127.0.0.1:43829_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> 2473726 INFO (zkCallback-2835-thread-2-processing-n:127.0.0.1:34842_solr) [n:127.0.0.1:34842_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2473726 INFO (zkCallback-2835-thread-1-processing-n:127.0.0.1:34842_solr) [n:127.0.0.1:34842_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2473730 INFO (zkCallback-2831-thread-2-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2473730 INFO (zkCallback-2831-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2473868 INFO (OverseerCollectionConfigSetProcessor-98948315931607046-127.0.0.1:43829_solr-n_0000000000) [n:127.0.0.1:43829_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> 2474620 INFO (qtp1393580694-10888) [n:127.0.0.1:43829_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&replicationFactor=1&name=coll1&nrtReplicas=1&action=CREATE&numShards=2&tlogReplicas=0&wt=javabin&version=2} status=0 QTime=6926
[junit4] 2> 2474762 INFO (qtp1393580694-10889) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [coll1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1 (1583173067020959744)]} 0 90
[junit4] 2> 2474766 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.c.ChaosMonkey monkey: stop jetty! 43829
[junit4] 2> 2474801 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2017278519
[junit4] 2> 2474801 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2474801 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1567047f: rootName = solr_43829, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@2c07dba3
[junit4] 2> 2474822 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2474822 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4f72897a: rootName = solr_43829, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@9e2c824
[junit4] 2> 2474837 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2474837 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@29269417: rootName = solr_43829, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@ad202d1
[junit4] 2> 2474838 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:43829_solr
[junit4] 2> 2474838 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2474839 INFO (zkCallback-2842-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2474892 INFO (coreCloseExecutor-3290-thread-1) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrCore [coll1_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@115b16fb
[junit4] 2> 2474892 INFO (coreCloseExecutor-3290-thread-1) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.coll1.shard1.replica_n1, tag=291182331
[junit4] 2> 2474892 INFO (coreCloseExecutor-3290-thread-1) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4a89b038: rootName = solr_43829, domain = solr.core.coll1.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.coll1.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@70bd95ac
[junit4] 2> 2474916 INFO (zkCallback-2831-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2474929 INFO (zkCallback-2835-thread-1-processing-n:127.0.0.1:34842_solr) [n:127.0.0.1:34842_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2474932 INFO (coreCloseExecutor-3290-thread-1) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.coll1.shard1.leader, tag=291182331
[junit4] 2> 2474967 INFO (coreCloseExecutor-3290-thread-1) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
[junit4] 2> 2474967 INFO (coreCloseExecutor-3290-thread-1) [n:127.0.0.1:43829_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7a45fedc commitCommandVersion:0
[junit4] 2> 2475019 INFO (zkCallback-2835-thread-1-processing-n:127.0.0.1:34842_solr) [n:127.0.0.1:34842_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2475019 INFO (zkCallback-2835-thread-2-processing-n:127.0.0.1:34842_solr) [n:127.0.0.1:34842_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2475026 INFO (zkCallback-2831-thread-1-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2475034 INFO (zkCallback-2831-thread-3-processing-n:127.0.0.1:43829_solr) [n:127.0.0.1:43829_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2475663 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.c.Overseer Overseer (id=98948315931607046-127.0.0.1:43829_solr-n_0000000000) closing
[junit4] 2> 2475673 INFO (OverseerStateUpdate-98948315931607046-127.0.0.1:43829_solr-n_0000000000) [n:127.0.0.1:43829_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:43829_solr
[junit4] 2> 2475714 WARN (OverseerAutoScalingTriggerThread-98948315931607046-127.0.0.1:43829_solr-n_0000000000) [n:127.0.0.1:43829_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 2475788 INFO (zkCallback-2835-thread-2-processing-n:127.0.0.1:34842_solr) [n:127.0.0.1:34842_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:34842_solr
[junit4] 2> 2475788 INFO (zkCallback-2835-thread-2-processing-n:127.0.0.1:34842_solr) [n:127.0.0.1:34842_solr ] o.a.s.c.Overseer Overseer (id=98948315931607045-127.0.0.1:34842_solr-n_0000000001) starting
[junit4] 2> 2475965 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@26b093f9{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 2476052 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3f2f6e5d{/solr,null,UNAVAILABLE}
[junit4] 2> 2476073 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.c.ChaosMonkey monkey: stop jetty! 34842
[junit4] 2> 2476073 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1311914831
[junit4] 2> 2476073 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2476073 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@15d6c627: rootName = solr_34842, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1f708aee
[junit4] 2> 2476078 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2476078 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@79742118: rootName = solr_34842, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@9e2c824
[junit4] 2> 2476081 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2476081 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@255fa089: rootName = solr_34842, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@ad202d1
[junit4] 2> 2476082 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:34842_solr
[junit4] 2> 2476083 INFO (zkCallback-2842-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 2476083 INFO (zkCallback-2835-thread-2-processing-n:127.0.0.1:34842_solr) [n:127.0.0.1:34842_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 2476083 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2476160 INFO (zkCallback-2835-thread-2-processing-n:127.0.0.1:34842_solr) [n:127.0.0.1:34842_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [0])
[junit4] 2> 2476160 INFO (zkCallback-2835-thread-1-processing-n:127.0.0.1:34842_solr) [n:127.0.0.1:34842_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [0])
[junit4] 2> 2476185 INFO (coreCloseExecutor-3296-thread-1) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrCore [coll1_shard2_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@77a0dff2
[junit4] 2> 2476185 INFO (coreCloseExecutor-3296-thread-1) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.coll1.shard2.replica_n2, tag=2007031794
[junit4] 2> 2476185 INFO (coreCloseExecutor-3296-thread-1) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@346d06d9: rootName = solr_34842, domain = solr.core.coll1.shard2.replica_n2, service url = null, agent id = null] for registry solr.core.coll1.shard2.replica_n2 / com.codahale.metrics.MetricRegistry@4a204a52
[junit4] 2> 2476220 INFO (coreCloseExecutor-3296-thread-1) [n:127.0.0.1:34842_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.coll1.shard2.leader, tag=2007031794
[junit4] 2> 2476490 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.c.Overseer Overseer (id=98948315931607045-127.0.0.1:34842_solr-n_0000000001) closing
[junit4] 2> 2476490 WARN (OverseerAutoScalingTriggerThread-98948315931607045-127.0.0.1:34842_solr-n_0000000001) [n:127.0.0.1:34842_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 2476701 INFO (OverseerStateUpdate-98948315931607045-127.0.0.1:34842_solr-n_0000000001) [n:127.0.0.1:34842_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:34842_solr
[junit4] 2> 2477136 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@344584b8{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 2477159 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5eed3ebc{/solr,null,UNAVAILABLE}
[junit4] 2> 2477230 ERROR (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] 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> 2477246 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[13F2C729CFF60E7D]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:36451 36451
[junit4] 2> 2477577 INFO (Thread-1958) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:36451 36451
[junit4] 2> 2477589 WARN (Thread-1958) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 2 /solr/configs/coll1/managed-schema
[junit4] 2> 2 /solr/configs/coll1
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 18 /solr/collections/coll1/state.json
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2> 2 /solr/autoscaling.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 3 /solr/collections
[junit4] 2> 2 /solr/overseer/queue
[junit4] 2> 2 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 2 /solr/overseer/collection-queue-work
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> 2477629 INFO (Thread-1979) [ ] o.a.s.c.ChaosMonkey monkey: starting jetty! 43829
[junit4] 2> 2477630 INFO (Thread-1979) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 2477630 INFO (Thread-1980) [ ] o.a.s.c.ChaosMonkey monkey: starting jetty! 34842
[junit4] 2> 2477630 INFO (Thread-1980) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 2477631 INFO (Thread-1979) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4cd5b452{/solr,null,AVAILABLE}
[junit4] 2> 2477631 INFO (Thread-1979) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1892ef1e{SSL,[ssl, http/1.1]}{127.0.0.1:43829}
[junit4] 2> 2477632 INFO (Thread-1979) [ ] o.e.j.s.Server Started @2493655ms
[junit4] 2> 2477632 INFO (Thread-1979) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=43829}
[junit4] 2> 2477632 ERROR (Thread-1979) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2477632 INFO (Thread-1979) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 2477632 INFO (Thread-1979) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2477632 INFO (Thread-1979) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2477644 INFO (Thread-1979) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-11-04T21:38:15.404Z
[junit4] 2> 2477714 INFO (Thread-1980) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f244e7c{/solr,null,AVAILABLE}
[junit4] 2> 2477714 INFO (Thread-1980) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@226103aa{SSL,[ssl, http/1.1]}{127.0.0.1:34842}
[junit4] 2> 2477714 INFO (Thread-1980) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1f244e7c{/solr,null,UNAVAILABLE}
[junit4] 2> 2477734 WARN (zkCallback-2842-thread-2) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4149544d name: ZooKeeperConnection Watcher:127.0.0.1:36451/solr got event WatchedEvent state:Disconnected type:None path:null path: null type: None
[junit4] 2> 2477734 WARN (zkCallback-2842-thread-2) [ ] o.a.s.c.c.ConnectionManager zkClient has disconnected
[junit4] 2> 2477742 WARN (Thread-1979-SendThread(127.0.0.1:36451)) [ ] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141)
[junit4] 2> 2479080 WARN (Thread-1979-SendThread(127.0.0.1:36451)) [ ] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141)
[junit4] 2> 2479601 WARN (SUITE-ZkFailoverTest-seed#[13F2C729CFF60E7D]-worker-SendThread(127.0.0.1:36451)) [ ] o.a.z.ClientCnxn Session 0x15f88f8163a0008 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141)
[junit4] 2> 2480266 WARN (Thread-1979-SendThread(127.0.0.1:36451)) [ ] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.So
[...truncated too long message...]
eper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:173)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Nov 04, 2017 9:39:40 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=11011, name=ProcessThread(sid:0 cport:36451):, state=WAITING, group=TGRP-ZkFailoverTest]
[junit4] 2> 2) Thread[id=11007, name=Thread-2005, state=WAITING, group=TGRP-ZkFailoverTest]
[junit4] 2> 3) Thread[id=11009, name=SessionTracker, state=TIMED_WAITING, group=TGRP-ZkFailoverTest]
[junit4] 2> 4) Thread[id=11008, name=NIOServerCxn.Factory:0.0.0.0/0.0.0.0:36451, state=RUNNABLE, group=TGRP-ZkFailoverTest]
[junit4] 2> 2563106 ERROR (ProcessThread(sid:0 cport:36451):) [ ] o.a.z.s.ZooKeeperCriticalThread Severe unrecoverable error, from thread : ProcessThread(sid:0 cport:36451):
[junit4] 2> java.lang.InterruptedException
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2014)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2048)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at org.apache.zookeeper.server.PrepRequestProcessor.run(PrepRequestProcessor.java:122)
[junit4] 2> 2563106 ERROR (ProcessThread(sid:0 cport:36451):) [ ] 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> 2563107 WARN (Thread-2005) [ ] o.a.s.c.ZkTestServer Server interrupted
[junit4] 2> java.lang.InterruptedException
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Thread.join(Thread.java:1252)
[junit4] 2> at java.lang.Thread.join(Thread.java:1326)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.join(NIOServerCnxnFactory.java:297)
[junit4] 2> at org.apache.solr.cloud.ZkTestServer$ZKServerMain.runFromConfig(ZkTestServer.java:309)
[junit4] 2> at org.apache.solr.cloud.ZkTestServer$2.run(ZkTestServer.java:490)
[junit4] 2> 2563107 ERROR (SessionTracker) [ ] o.a.z.s.ZooKeeperCriticalThread Severe unrecoverable error, from thread : SessionTracker
[junit4] 2> java.lang.InterruptedException
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:146)
[junit4] 2> 2563177 ERROR (SessionTracker) [ ] 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> Nov 04, 2017 9:39:44 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> SEVERE: There are still zombie threads that couldn't be terminated:
[junit4] 2> 1) Thread[id=11008, name=NIOServerCxn.Factory:0.0.0.0/0.0.0.0:36451, state=RUNNABLE, group=TGRP-ZkFailoverTest]
[junit4] 2> at sun.nio.ch.IOUtil.drain(Native Method)
[junit4] 2> at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:104)
[junit4] 2> at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
[junit4] 2> at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:173)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=630, maxMBSortInHeap=5.059510615785986, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@279082de), locale=es-BO, timezone=America/Virgin
[junit4] 2> NOTE: Linux 4.4.0-83-generic amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=4,threads=3,free=129086920,total=527958016
[junit4] 2> NOTE: All tests run in this JVM: [SimpleCollectionCreateDeleteTest, TestAnalyzedSuggestions, PreAnalyzedFieldTest, DataDrivenBlockJoinTest, AssignTest, ImplicitSnitchTest, TestSolrConfigHandler, BlockDirectoryTest, TestStressCloudBlindAtomicUpdates, TestBM25SimilarityFactory, SortSpecParsingTest, BigEndianAscendingWordSerializerTest, MissingSegmentRecoveryTest, TestCloudManagedSchema, TestLegacyTerms, PingRequestHandlerTest, TestRTGBase, BadIndexSchemaTest, InfixSuggestersTest, CollectionTooManyReplicasTest, TolerantUpdateProcessorTest, TestOmitPositions, DocValuesMultiTest, GraphQueryTest, TestConfigSetProperties, HdfsUnloadDistributedZkTest, TestSQLHandler, HdfsLockFactoryTest, TestCloudPseudoReturnFields, TestDocSet, DistributedQueryComponentOptimizationTest, SmileWriterTest, TestSolrFieldCacheBean, DeleteLastCustomShardedReplicaTest, HdfsAutoAddReplicasIntegrationTest, TestDocumentBuilder, OpenExchangeRatesOrgProviderTest, TestReloadDeadlock, TestCryptoKeys, BinaryUpdateRequestHandlerTest, CdcrBidirectionalTest, CircularListTest, TestPolicyCloud, NotRequiredUniqueKeyTest, TestReloadAndDeleteDocs, NumberUtilsTest, TestExactSharedStatsCache, SpatialHeatmapFacetsTest, EnumFieldTest, TestSha256AuthenticationProvider, TestDFISimilarityFactory, HighlighterConfigTest, DistributedFacetPivotSmallTest, MBeansHandlerTest, SolrJmxReporterTest, ResponseHeaderTest, TestQuerySenderNoQuery, OutOfBoxZkACLAndCredentialsProvidersTest, SolrXmlInZkTest, TestAnalyzeInfixSuggestions, ConfigureRecoveryStrategyTest, TestOnReconnectListenerSupport, JvmMetricsTest, TestStressReorder, TestManagedStopFilterFactory, TestLuceneMatchVersion, TestAuthorizationFramework, TestBackupRepositoryFactory, ConcurrentDeleteAndCreateCollectionTest, TestSimpleTrackingShardHandler, SpellingQueryConverterTest, TestRequestStatusCollectionAPI, SampleTest, TestCollationField, CloudMLTQParserTest, CollectionsAPIDistributedZkTest, HdfsChaosMonkeySafeLeaderTest, BasicAuthIntegrationTest, TestAtomicUpdateErrorCases, TestDistributedStatsComponentCardinality, ZkFailoverTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ZkFailoverTest -Dtests.seed=13F2C729CFF60E7D -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=es-BO -Dtests.timezone=America/Virgin -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J1 | ZkFailoverTest (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 4 threads leaked from SUITE scope at org.apache.solr.cloud.ZkFailoverTest:
[junit4] > 1) Thread[id=11011, name=ProcessThread(sid:0 cport:36451):, state=WAITING, group=TGRP-ZkFailoverTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at org.apache.zookeeper.server.PrepRequestProcessor.run(PrepRequestProcessor.java:122)
[junit4] > 2) Thread[id=11007, name=Thread-2005, state=WAITING, group=TGRP-ZkFailoverTest]
[junit4] > at java.lang.Object.wait(Native Method)
[junit4] > at java.lang.Thread.join(Thread.java:1252)
[junit4] > at java.lang.Thread.join(Thread.java:1326)
[junit4] > at org.apache.zookeeper.server.NIOServerCnxnFactory.join(NIOServerCnxnFactory.java:297)
[junit4] > at org.apache.solr.cloud.ZkTestServer$ZKServerMain.runFromConfig(ZkTestServer.java:309)
[junit4] > at org.apache.solr.cloud.ZkTestServer$2.run(ZkTestServer.java:490)
[junit4] > 3) Thread[id=11009, name=SessionTracker, state=TIMED_WAITING, group=TGRP-ZkFailoverTest]
[junit4] > at java.lang.Object.wait(Native Method)
[junit4] > at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:146)
[junit4] > 4) Thread[id=11008, name=NIOServerCxn.Factory:0.0.0.0/0.0.0.0:36451, state=RUNNABLE, group=TGRP-ZkFailoverTest]
[junit4] > at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
[junit4] > at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
[junit4] > at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
[junit4] > at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
[junit4] > at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
[junit4] > at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:173)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at __randomizedtesting.SeedInfo.seed([13F2C729CFF60E7D]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=11008, name=NIOServerCxn.Factory:0.0.0.0/0.0.0.0:36451, state=RUNNABLE, group=TGRP-ZkFailoverTest]
[junit4] > at sun.nio.ch.IOUtil.drain(Native Method)
[junit4] > at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:104)
[junit4] > at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
[junit4] > at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
[junit4] > at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:173)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at __randomizedtesting.SeedInfo.seed([13F2C729CFF60E7D]:0)
[junit4] Completed [232/749 (1!)] on J1 in 103.35s, 1 test, 1 failure, 2 errors <<< FAILURES!
[...truncated 48859 lines...]
[JENKINS] Lucene-Solr-Tests-master - Build # 2159 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2159/
All tests passed
Build Log:
[...truncated 1010 lines...]
[junit4] JVM J0: stdout was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/temp/junit4-J0-20171104_174605_7006567000325183447073.sysout
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] #
[junit4] # There is insufficient memory for the Java Runtime Environment to continue.
[junit4] # Native memory allocation (mmap) failed to map 100139008 bytes for committing reserved memory.
[junit4] # An error report file with more information is saved as:
[junit4] # /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/J0/hs_err_pid28095.log
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J0: stderr was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/temp/junit4-J0-20171104_174605_7002199838189212956794.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: INFO: os::commit_memory(0x00000000e9280000, 100139008, 0) failed; error='Cannot allocate memory' (errno=12)
[junit4] <<< JVM J0: EOF ----
[...truncated 841 lines...]
[junit4] ERROR: JVM J0 ended with an exception, command line: /usr/local/asfpackages/java/jdk1.8.0_144/jre/bin/java -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/heapdumps -ea -esa -Dtests.prefix=tests -Dtests.seed=7D04CF33378F8F42 -Xmx512M -Dtests.iters= -Dtests.verbose=false -Dtests.infostream=false -Dtests.codec=random -Dtests.postingsformat=random -Dtests.docvaluesformat=random -Dtests.locale=random -Dtests.timezone=random -Dtests.directory=random -Dtests.linedocsfile=europarl.lines.txt.gz -Dtests.luceneMatchVersion=8.0.0 -Dtests.cleanthreads=perMethod -Djava.util.logging.config.file=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/tools/junit4/logging.properties -Dtests.nightly=false -Dtests.weekly=false -Dtests.monster=false -Dtests.slow=true -Dtests.asserts=true -Dtests.multiplier=2 -DtempDir=./temp -Djava.io.tmpdir=./temp -Djunit4.tempDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/temp -Dcommon.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene -Dclover.db.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/clover/db -Djava.security.policy=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/tools/junit4/tests.policy -Dtests.LUCENE_VERSION=8.0.0 -Djetty.testMode=1 -Djetty.insecurerandom=1 -Dsolr.directoryFactory=org.apache.solr.core.MockDirectoryFactory -Djava.awt.headless=true -Djdk.map.althashing.threshold=0 -Dtests.src.home=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master -Djava.security.egd=file:/dev/./urandom -Djunit4.childvm.cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/J0 -Djunit4.childvm.id=0 -Djunit4.childvm.count=3 -Dtests.leaveTemporary=false -Dtests.filterstacks=true -Djava.security.manager=org.apache.lucene.util.TestSecurityManager -Dfile.encoding=UTF-8 -classpath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/codecs/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/test-framework/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/test-framework/lib/junit-4.10.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/test-framework/lib/randomizedtesting-runner-2.5.3.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/classes/test:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-launcher.jar:/x1/jenkins/.ant/lib/ivy-2.4.0.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-junit.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-log4j.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-junit4.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jai.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-javamail.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-bsf.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-commons-net.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-antlr.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jsch.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-oro.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-commons-logging.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-netrexx.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-testutil.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jdepend.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-bcel.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-xalan2.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-resolver.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jmf.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-regexp.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-swing.jar:/usr/local/asfpackages/java/jdk1.8.0_144/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.5.3.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/temp/junit4-J0-20171104_174605_7004292236879464795530.events @/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/temp/junit4-J0-20171104_174605_7004357389213496347083.suites -stdin
[junit4] ERROR: JVM J0 ended with an exception: Forked process returned with error code: 1. Very likely a JVM crash. See process stdout at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/temp/junit4-J0-20171104_174605_7006567000325183447073.sysout See process stderr at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/temp/junit4-J0-20171104_174605_7002199838189212956794.syserr
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1519)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:126)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:982)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:979)
[junit4] at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] at java.lang.Thread.run(Thread.java:748)
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:826: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:770: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build.xml:50: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/common-build.xml:1505: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/common-build.xml:1032: At least one slave process threw an exception, first: Forked process returned with error code: 1. Very likely a JVM crash. See process stdout at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/temp/junit4-J0-20171104_174605_7006567000325183447073.sysout See process stderr at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/temp/junit4-J0-20171104_174605_7002199838189212956794.syserr
Total time: 63 minutes 42 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[Fast Archiver] No artifacts from Lucene-Solr-Tests-master #2153 to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any