You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/12/15 00:41:11 UTC
[JENKINS] Lucene-Solr-master-Windows (64bit/jdk1.8.0_112) - Build #
6289 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6289/
Java: 64bit/jdk1.8.0_112 -XX:+UseCompressedOops -XX:+UseG1GC
4 tests failed.
FAILED: org.apache.solr.morphlines.solr.SolrMorphlineZkAliasTest.test
Error Message:
Cannot download schema.xml from ZooKeeper near: { # String: 1 "solrUrl" : "", # String: 1 "collection" : "aliascollection", # String: 1 "zkHost" : "127.0.0.1:57064/solr", # String: 1 "batchSize" : "1000", # String: 1 "solrHomeDir" : "" }
Stack Trace:
org.kitesdk.morphline.api.MorphlineCompilationException: Cannot download schema.xml from ZooKeeper near: {
# String: 1
"solrUrl" : "",
# String: 1
"collection" : "aliascollection",
# String: 1
"zkHost" : "127.0.0.1:57064/solr",
# String: 1
"batchSize" : "1000",
# String: 1
"solrHomeDir" : ""
}
at __randomizedtesting.SeedInfo.seed([72870D3BD771FC33:FAD332E1798D91CB]:0)
at org.apache.solr.morphlines.solr.SolrLocator.getIndexSchema(SolrLocator.java:148)
at org.apache.solr.morphlines.solr.SanitizeUnknownSolrFieldsBuilder$SanitizeUnknownSolrFields.<init>(SanitizeUnknownSolrFieldsBuilder.java:70)
at org.apache.solr.morphlines.solr.SanitizeUnknownSolrFieldsBuilder.build(SanitizeUnknownSolrFieldsBuilder.java:52)
at org.kitesdk.morphline.base.AbstractCommand.buildCommand(AbstractCommand.java:302)
at org.kitesdk.morphline.base.AbstractCommand.buildCommandChain(AbstractCommand.java:249)
at org.kitesdk.morphline.stdlib.Pipe.<init>(Pipe.java:46)
at org.kitesdk.morphline.stdlib.PipeBuilder.build(PipeBuilder.java:40)
at org.apache.solr.morphlines.solr.AbstractSolrMorphlineZkTestBase.createMorphline(AbstractSolrMorphlineZkTestBase.java:104)
at org.apache.solr.morphlines.solr.AbstractSolrMorphlineZkTestBase.parse(AbstractSolrMorphlineZkTestBase.java:100)
at org.apache.solr.morphlines.solr.SolrMorphlineZkAliasTest.test(SolrMorphlineZkAliasTest.java:47)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Caused by: java.io.FileNotFoundException: .\temp\1481761501723-0 (Access is denied)
at java.io.FileInputStream.open0(Native Method)
at java.io.FileInputStream.open(FileInputStream.java:195)
at java.io.FileInputStream.<init>(FileInputStream.java:138)
at com.google.common.io.Files$FileByteSource.openStream(Files.java:124)
at com.google.common.io.Files$FileByteSource.openStream(Files.java:114)
at com.google.common.io.ByteSource.copyTo(ByteSource.java:202)
at com.google.common.io.Files.copy(Files.java:436)
at com.google.common.io.Files.move(Files.java:651)
at org.apache.solr.morphlines.solr.ZooKeeperDownloader.downloadConfigDir(ZooKeeperDownloader.java:120)
at org.apache.solr.morphlines.solr.SolrLocator.getIndexSchema(SolrLocator.java:145)
... 48 more
FAILED: org.apache.solr.cloud.RecoveryZkTest.test
Error Message:
Mismatch in counts between replicas
Stack Trace:
java.lang.AssertionError: Mismatch in counts between replicas
at __randomizedtesting.SeedInfo.seed([D6E6EBE17CC67D26:5EB2D43BD23A10DE]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.RecoveryZkTest.assertShardConsistency(RecoveryZkTest.java:143)
at org.apache.solr.cloud.RecoveryZkTest.test(RecoveryZkTest.java:126)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestCloudRecovery
Error Message:
Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard2_replica1\data\tlog\tlog.0000000000000000004: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard2_replica1\data\tlog\tlog.0000000000000000004: The process cannot access the file because it is being used by another process. C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard2_replica1\data\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard2_replica1\data\tlog C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard2_replica1\data: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard2_replica1\data C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard2_replica1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard2_replica1 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard1_replica1\data\tlog\tlog.0000000000000000004: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard1_replica1\data\tlog\tlog.0000000000000000004: The process cannot access the file because it is being used by another process. C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard1_replica1\data\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard1_replica1\data\tlog C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard1_replica1\data: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard1_replica1\data C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard1_replica1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard1_replica1 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard2_replica2\data\tlog\tlog.0000000000000000004: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard2_replica2\data\tlog\tlog.0000000000000000004: The process cannot access the file because it is being used by another process. C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard2_replica2\data\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard2_replica2\data\tlog C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard2_replica2\data: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard2_replica2\data C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard2_replica2: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard2_replica2 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard1_replica2\data\tlog\tlog.0000000000000000004: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard1_replica2\data\tlog\tlog.0000000000000000004: The process cannot access the file because it is being used by another process. C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard1_replica2\data\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard1_replica2\data\tlog C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard1_replica2\data: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard1_replica2\data C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard1_replica2: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard1_replica2 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard2_replica1\data\tlog\tlog.0000000000000000004: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard2_replica1\data\tlog\tlog.0000000000000000004: The process cannot access the file because it is being used by another process.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard2_replica1\data\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard2_replica1\data\tlog
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard2_replica1\data: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard2_replica1\data
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard2_replica1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard2_replica1
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard1_replica1\data\tlog\tlog.0000000000000000004: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard1_replica1\data\tlog\tlog.0000000000000000004: The process cannot access the file because it is being used by another process.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard1_replica1\data\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard1_replica1\data\tlog
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard1_replica1\data: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard1_replica1\data
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard1_replica1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard1_replica1
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard2_replica2\data\tlog\tlog.0000000000000000004: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard2_replica2\data\tlog\tlog.0000000000000000004: The process cannot access the file because it is being used by another process.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard2_replica2\data\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard2_replica2\data\tlog
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard2_replica2\data: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard2_replica2\data
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard2_replica2: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard2_replica2
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard1_replica2\data\tlog\tlog.0000000000000000004: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard1_replica2\data\tlog\tlog.0000000000000000004: The process cannot access the file because it is being used by another process.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard1_replica2\data\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard1_replica2\data\tlog
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard1_replica2\data: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard1_replica2\data
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard1_replica2: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard1_replica2
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001
at __randomizedtesting.SeedInfo.seed([D6E6EBE17CC67D26]:0)
at org.apache.lucene.util.IOUtils.rm(IOUtils.java:323)
at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216)
at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
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:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail
Error Message:
expected:<200> but was:<404>
Stack Trace:
java.lang.AssertionError: expected:<200> but was:<404>
at __randomizedtesting.SeedInfo.seed([D6E6EBE17CC67D26:BE59DECBAC5C6FCA]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.cancelDelegationToken(TestSolrCloudWithDelegationTokens.java:140)
at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail(TestSolrCloudWithDelegationTokens.java:294)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11653 lines...]
[junit4] Suite: org.apache.solr.cloud.TestCloudRecovery
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\init-core-data-001
[junit4] 2> 1437831 INFO (SUITE-TestCloudRecovery-seed#[D6E6EBE17CC67D26]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 1437832 INFO (SUITE-TestCloudRecovery-seed#[D6E6EBE17CC67D26]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001
[junit4] 2> 1437832 INFO (SUITE-TestCloudRecovery-seed#[D6E6EBE17CC67D26]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1437833 INFO (Thread-2683) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1437833 INFO (Thread-2683) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1437934 INFO (SUITE-TestCloudRecovery-seed#[D6E6EBE17CC67D26]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:62570
[junit4] 2> 1437947 INFO (jetty-launcher-1709-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1437947 INFO (jetty-launcher-1709-thread-2) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1437948 INFO (jetty-launcher-1709-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@527c8184{/solr,null,AVAILABLE}
[junit4] 2> 1437948 INFO (jetty-launcher-1709-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3408b67d{/solr,null,AVAILABLE}
[junit4] 2> 1437949 INFO (jetty-launcher-1709-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3f38e507{HTTP/1.1,[http/1.1]}{127.0.0.1:62574}
[junit4] 2> 1437949 INFO (jetty-launcher-1709-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5ad191e0{HTTP/1.1,[http/1.1]}{127.0.0.1:62575}
[junit4] 2> 1437949 INFO (jetty-launcher-1709-thread-2) [ ] o.e.j.s.Server Started @1443230ms
[junit4] 2> 1437949 INFO (jetty-launcher-1709-thread-1) [ ] o.e.j.s.Server Started @1443230ms
[junit4] 2> 1437949 INFO (jetty-launcher-1709-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62574}
[junit4] 2> 1437949 INFO (jetty-launcher-1709-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62575}
[junit4] 2> 1437949 ERROR (jetty-launcher-1709-thread-2) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1437949 ERROR (jetty-launcher-1709-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1437950 INFO (jetty-launcher-1709-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 1437950 INFO (jetty-launcher-1709-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 1437950 INFO (jetty-launcher-1709-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1437950 INFO (jetty-launcher-1709-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1437950 INFO (jetty-launcher-1709-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1437950 INFO (jetty-launcher-1709-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1437950 INFO (jetty-launcher-1709-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-12-14T23:57:09.672Z
[junit4] 2> 1437950 INFO (jetty-launcher-1709-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-12-14T23:57:09.672Z
[junit4] 2> 1437956 INFO (jetty-launcher-1709-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1437956 INFO (jetty-launcher-1709-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1437968 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x158ffc4ae360002, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1437971 INFO (jetty-launcher-1709-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62570/solr
[junit4] 2> 1437971 INFO (jetty-launcher-1709-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62570/solr
[junit4] 2> 1437975 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x158ffc4ae360003, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1438030 INFO (jetty-launcher-1709-thread-2) [n:127.0.0.1:62574_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:62574_solr
[junit4] 2> 1438032 INFO (jetty-launcher-1709-thread-2) [n:127.0.0.1:62574_solr ] o.a.s.c.Overseer Overseer (id=97108612189913093-127.0.0.1:62574_solr-n_0000000000) starting
[junit4] 2> 1438033 INFO (jetty-launcher-1709-thread-1) [n:127.0.0.1:62575_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62575_solr
[junit4] 2> 1438044 INFO (zkCallback-1719-thread-1-processing-n:127.0.0.1:62574_solr) [n:127.0.0.1:62574_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1438044 INFO (zkCallback-1720-thread-1-processing-n:127.0.0.1:62575_solr) [n:127.0.0.1:62575_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1438053 INFO (jetty-launcher-1709-thread-2) [n:127.0.0.1:62574_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62574_solr
[junit4] 2> 1438079 INFO (zkCallback-1720-thread-1-processing-n:127.0.0.1:62575_solr) [n:127.0.0.1:62575_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1438092 INFO (jetty-launcher-1709-thread-2) [n:127.0.0.1:62574_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\.
[junit4] 2> 1438094 INFO (jetty-launcher-1709-thread-1) [n:127.0.0.1:62575_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\.
[junit4] 2> 1438097 INFO (zkCallback-1719-thread-1-processing-n:127.0.0.1:62574_solr) [n:127.0.0.1:62574_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1438108 INFO (SUITE-TestCloudRecovery-seed#[D6E6EBE17CC67D26]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1438109 INFO (SUITE-TestCloudRecovery-seed#[D6E6EBE17CC67D26]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62570/solr ready
[junit4] 2> 1438116 INFO (qtp325819302-11686) [n:127.0.0.1:62574_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&collection.configName=config&maxShardsPerNode=2&name=collection1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1438119 INFO (OverseerThreadFactory-5365-thread-1-processing-n:127.0.0.1:62574_solr) [n:127.0.0.1:62574_solr ] o.a.s.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 1438235 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=config&newCollection=true&name=collection1_shard2_replica2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2
[junit4] 2> 1438242 INFO (qtp2136604218-11676) [n:127.0.0.1:62575_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=config&newCollection=true&name=collection1_shard1_replica1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2
[junit4] 2> 1438242 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=config&newCollection=true&name=collection1_shard1_replica2&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2
[junit4] 2> 1438244 INFO (qtp2136604218-11677) [n:127.0.0.1:62575_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=config&newCollection=true&name=collection1_shard2_replica1&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2
[junit4] 2> 1438355 INFO (zkCallback-1719-thread-1-processing-n:127.0.0.1:62574_solr) [n:127.0.0.1:62574_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1438355 INFO (zkCallback-1720-thread-1-processing-n:127.0.0.1:62575_solr) [n:127.0.0.1:62575_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1439251 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1439258 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1439260 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.s.IndexSchema [collection1_shard2_replica2] Schema name=minimal
[junit4] 2> 1439263 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1439266 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica2' using configuration from collection collection1
[junit4] 2> 1439266 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.c.SolrCore [[collection1_shard2_replica2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard2_replica2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\.\collection1_shard2_replica2\data\]
[junit4] 2> 1439271 INFO (qtp2136604218-11676) [n:127.0.0.1:62575_solr c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1439278 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.s.IndexSchema [collection1_shard1_replica2] Schema name=minimal
[junit4] 2> 1439280 INFO (qtp2136604218-11676) [n:127.0.0.1:62575_solr c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.s.IndexSchema [collection1_shard1_replica1] Schema name=minimal
[junit4] 2> 1439284 INFO (qtp2136604218-11676) [n:127.0.0.1:62575_solr c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1439284 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1439289 INFO (qtp2136604218-11676) [n:127.0.0.1:62575_solr c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica1' using configuration from collection collection1
[junit4] 2> 1439289 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica2' using configuration from collection collection1
[junit4] 2> 1439289 INFO (qtp2136604218-11676) [n:127.0.0.1:62575_solr c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.c.SolrCore [[collection1_shard1_replica1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard1_replica1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\.\collection1_shard1_replica1\data\]
[junit4] 2> 1439289 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.c.SolrCore [[collection1_shard1_replica2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\collection1_shard1_replica2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node2\.\collection1_shard1_replica2\data\]
[junit4] 2> 1439316 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1439316 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1439317 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1439317 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1439331 INFO (qtp2136604218-11677) [n:127.0.0.1:62575_solr c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1439343 INFO (qtp2136604218-11677) [n:127.0.0.1:62575_solr c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.s.IndexSchema [collection1_shard2_replica1] Schema name=minimal
[junit4] 2> 1439346 INFO (qtp2136604218-11677) [n:127.0.0.1:62575_solr c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1439350 INFO (qtp2136604218-11677) [n:127.0.0.1:62575_solr c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica1' using configuration from collection collection1
[junit4] 2> 1439351 INFO (qtp2136604218-11677) [n:127.0.0.1:62575_solr c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.c.SolrCore [[collection1_shard2_replica1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\collection1_shard2_replica1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestCloudRecovery_D6E6EBE17CC67D26-001\tempDir-001\node1\.\collection1_shard2_replica1\data\]
[junit4] 2> 1439370 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1439370 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1439370 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1439370 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1439379 INFO (qtp2136604218-11676) [n:127.0.0.1:62575_solr c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1439379 INFO (qtp2136604218-11676) [n:127.0.0.1:62575_solr c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1439379 INFO (qtp2136604218-11676) [n:127.0.0.1:62575_solr c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1439379 INFO (qtp2136604218-11676) [n:127.0.0.1:62575_solr c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1439382 INFO (qtp2136604218-11677) [n:127.0.0.1:62575_solr c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1439382 INFO (qtp2136604218-11677) [n:127.0.0.1:62575_solr c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1439383 INFO (qtp2136604218-11677) [n:127.0.0.1:62575_solr c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1439383 INFO (qtp2136604218-11677) [n:127.0.0.1:62575_solr c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1439386 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a31cfb4[collection1_shard1_replica2] main]
[junit4] 2> 1439388 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 1439388 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 1439388 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.s.SolrIndexSearcher Opening [Searcher@489b651a[collection1_shard2_replica2] main]
[junit4] 2> 1439388 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1439388 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1553737796665999360
[junit4] 2> 1439389 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 1439389 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 1439390 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1439390 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1553737796668096512
[junit4] 2> 1439396 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9999ms
[junit4] 2> 1439398 INFO (qtp2136604218-11676) [n:127.0.0.1:62575_solr c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@61c0466f[collection1_shard1_replica1] main]
[junit4] 2> 1439399 INFO (qtp2136604218-11676) [n:127.0.0.1:62575_solr c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 1439399 INFO (qtp2136604218-11676) [n:127.0.0.1:62575_solr c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 1439399 INFO (qtp2136604218-11676) [n:127.0.0.1:62575_solr c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1439399 INFO (qtp2136604218-11676) [n:127.0.0.1:62575_solr c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1553737796677533696
[junit4] 2> 1439400 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard2: total=2 found=1 timeoutin=9999ms
[junit4] 2> 1439405 INFO (searcherExecutor-5368-thread-1-processing-n:127.0.0.1:62574_solr x:collection1_shard1_replica2 s:shard1 c:collection1) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.c.SolrCore [collection1_shard1_replica2] Registered new searcher Searcher@1a31cfb4[collection1_shard1_replica2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1439406 INFO (qtp2136604218-11677) [n:127.0.0.1:62575_solr c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@54b1e860[collection1_shard2_replica1] main]
[junit4] 2> 1439408 INFO (qtp2136604218-11677) [n:127.0.0.1:62575_solr c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 1439408 INFO (qtp2136604218-11677) [n:127.0.0.1:62575_solr c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 1439408 INFO (qtp2136604218-11677) [n:127.0.0.1:62575_solr c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1439408 INFO (qtp2136604218-11677) [n:127.0.0.1:62575_solr c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1553737796686970880
[junit4] 2> 1439416 INFO (searcherExecutor-5366-thread-1-processing-n:127.0.0.1:62574_solr x:collection1_shard2_replica2 s:shard2 c:collection1) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.c.SolrCore [collection1_shard2_replica2] Registered new searcher Searcher@489b651a[collection1_shard2_replica2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1439417 INFO (searcherExecutor-5367-thread-1-processing-n:127.0.0.1:62575_solr x:collection1_shard1_replica1 s:shard1 c:collection1) [n:127.0.0.1:62575_solr c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.c.SolrCore [collection1_shard1_replica1] Registered new searcher Searcher@61c0466f[collection1_shard1_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1439418 INFO (searcherExecutor-5373-thread-1-processing-n:127.0.0.1:62575_solr x:collection1_shard2_replica1 s:shard2 c:collection1) [n:127.0.0.1:62575_solr c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.c.SolrCore [collection1_shard2_replica1] Registered new searcher Searcher@54b1e860[collection1_shard2_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1439504 INFO (zkCallback-1720-thread-1-processing-n:127.0.0.1:62575_solr) [n:127.0.0.1:62575_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1439504 INFO (zkCallback-1719-thread-1-processing-n:127.0.0.1:62574_solr) [n:127.0.0.1:62574_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1439896 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1439896 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1439896 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:62574/solr/collection1_shard1_replica2/
[junit4] 2> 1439896 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.u.PeerSync PeerSync: core=collection1_shard1_replica2 url=http://127.0.0.1:62574/solr START replicas=[http://127.0.0.1:62575/solr/collection1_shard1_replica1/] nUpdates=100
[junit4] 2> 1439897 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.u.PeerSync PeerSync: core=collection1_shard1_replica2 url=http://127.0.0.1:62574/solr DONE. We have no versions. sync failed.
[junit4] 2> 1439898 INFO (qtp2136604218-11680) [n:127.0.0.1:62575_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica1] o.a.s.c.S.Request [collection1_shard1_replica1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1439899 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 1439899 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 1439900 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1439900 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1439900 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:62574/solr/collection1_shard2_replica2/
[junit4] 2> 1439900 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.u.PeerSync PeerSync: core=collection1_shard2_replica2 url=http://127.0.0.1:62574/solr START replicas=[http://127.0.0.1:62575/solr/collection1_shard2_replica1/] nUpdates=100
[junit4] 2> 1439900 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.u.PeerSync PeerSync: core=collection1_shard2_replica2 url=http://127.0.0.1:62574/solr DONE. We have no versions. sync failed.
[junit4] 2> 1439900 INFO (qtp2136604218-11699) [n:127.0.0.1:62575_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica1] o.a.s.c.S.Request [collection1_shard2_replica1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1439901 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 1439901 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 1439904 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:62574/solr/collection1_shard1_replica2/ shard1
[junit4] 2> 1439905 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:62574/solr/collection1_shard2_replica2/ shard2
[junit4] 2> 1440010 INFO (zkCallback-1720-thread-1-processing-n:127.0.0.1:62575_solr) [n:127.0.0.1:62575_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1440010 INFO (zkCallback-1719-thread-3-processing-n:127.0.0.1:62574_solr) [n:127.0.0.1:62574_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1440056 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1440058 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1440059 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 x:collection1_shard1_replica2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=config&newCollection=true&name=collection1_shard1_replica2&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2} status=0 QTime=1816
[junit4] 2> 1440061 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 x:collection1_shard2_replica2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=config&newCollection=true&name=collection1_shard2_replica2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2} status=0 QTime=1826
[junit4] 2> 1440162 INFO (zkCallback-1719-thread-2-processing-n:127.0.0.1:62574_solr) [n:127.0.0.1:62574_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1440162 INFO (zkCallback-1720-thread-1-processing-n:127.0.0.1:62575_solr) [n:127.0.0.1:62575_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1440408 INFO (qtp2136604218-11676) [n:127.0.0.1:62575_solr c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=config&newCollection=true&name=collection1_shard1_replica1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2} status=0 QTime=2166
[junit4] 2> 1440419 INFO (qtp2136604218-11677) [n:127.0.0.1:62575_solr c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=config&newCollection=true&name=collection1_shard2_replica1&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2} status=0 QTime=2174
[junit4] 2> 1440422 INFO (qtp325819302-11686) [n:127.0.0.1:62574_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> 1440521 INFO (zkCallback-1719-thread-1-processing-n:127.0.0.1:62574_solr) [n:127.0.0.1:62574_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1440521 INFO (zkCallback-1720-thread-1-processing-n:127.0.0.1:62575_solr) [n:127.0.0.1:62575_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1441422 INFO (qtp325819302-11686) [n:127.0.0.1:62574_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=2&collection.configName=config&maxShardsPerNode=2&name=collection1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=3306
[junit4] 2> 1441422 INFO (SUITE-TestCloudRecovery-seed#[D6E6EBE17CC67D26]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):30
[junit4] 2> 1441423 INFO (SUITE-TestCloudRecovery-seed#[D6E6EBE17CC67D26]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
[junit4] 2> 1441455 INFO (TEST-TestCloudRecovery.leaderRecoverFromLogOnStartupTest-seed#[D6E6EBE17CC67D26]) [ ] o.a.s.SolrTestCaseJ4 ###Starting leaderRecoverFromLogOnStartupTest
[junit4] 2> 1441466 INFO (qtp2136604218-11682) [n:127.0.0.1:62575_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&_version_=-1553737798834454528&distrib.from=http://127.0.0.1:62574/solr/collection1_shard2_replica2/&wt=javabin&version=2}{deleteByQuery=*:* (-1553737798834454528)} 0 3
[junit4] 2> 1441469 INFO (qtp2136604218-11697) [n:127.0.0.1:62575_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&_version_=-1553737798838648832&distrib.from=http://127.0.0.1:62574/solr/collection1_shard1_replica2/&wt=javabin&version=2}{deleteByQuery=*:* (-1553737798838648832)} 0 2
[junit4] 2> 1441469 INFO (qtp325819302-11700) [n:127.0.0.1:62574_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica2] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:62574/solr/collection1_shard2_replica2/&wt=javabin&version=2}{deleteByQuery=*:* (-1553737798838648832)} 0 9
[junit4] 2> 1441469 INFO (qtp325819302-11681) [n:127.0.0.1:62574_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1553737798834454528)} 0 13
[junit4] 2> 1441471 INFO (qtp325819302-11702) [n:127.0.0.1:62574_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica2] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1441471 INFO (qtp2136604218-11698) [n:127.0.0.1:62575_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1441471 INFO (qtp325819302-11702) [n:127.0.0.1:62574_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica2] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1441471 INFO (qtp2136604218-11698) [n:127.0.0.1:62575_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1441474 INFO (qtp2136604218-11679) [n:127.0.0.1:62575_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1441474 INFO (qtp2136604218-11679) [n:127.0.0.1:62575_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1441474 INFO (qtp2136604218-11698) [n:127.0.0.1:62575_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1441474 INFO (qtp325819302-11702) [n:127.0.0.1:62574_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica2] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1441474 INFO (qtp2136604218-11679) [n:127.0.0.1:62575_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1441474 INFO (qtp325819302-11702) [n:127.0.0.1:62574_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:62574/solr/collection1_shard1_replica2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 3
[junit4] 2> 1441474 INFO (qtp325819302-11683) [n:127.0.0.1:62574_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica2] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1441475 INFO (qtp325819302-11683) [n:127.0.0.1:62574_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica2] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1441474 INFO (qtp2136604218-11679) [n:127.0.0.1:62575_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:62574/solr/collection1_shard1_replica2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 1441474 INFO (qtp2136604218-11698) [n:127.0.0.1:62575_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:62574/solr/collection1_shard1_replica2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 3
[junit4] 2> 1441475 INFO (qtp325819302-11683) [n:127.0.0.1:62574_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica2] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1441475 INFO (qtp325819302-11683) [n:127.0.0.1:62574_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:62574/solr/collection1_shard1_replica2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 1441475 INFO (qtp325819302-11701) [n:127.0.0.1:62574_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica2] webapp=/solr path=/update params={_stateVer_=collection1:5&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 5
[junit4] 2> 1441485 INFO (qtp2136604218-11680) [n:127.0.0.1:62575_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:62574/solr/collection1_shard1_replica2/&wt=javabin&version=2}{add=[1 (1553737798858571776)]} 0 2
[junit4] 2> 1441485 INFO (qtp325819302-11702) [n:127.0.0.1:62574_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1 (1553737798858571776)]} 0 7
[junit4] 2> 1441492 INFO (qtp2136604218-11699) [n:127.0.0.1:62575_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:62574/solr/collection1_shard2_replica2/&wt=javabin&version=2}{add=[2 (1553737798865911808)]} 0 2
[junit4] 2> 1441492 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2 (1553737798865911808)]} 0 6
[junit4] 2> 1441493 INFO (qtp2136604218-11699) [n:127.0.0.1:62575_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:62574/solr/collection1_shard2_replica2/&wt=javabin&version=2}{add=[3 (1553737798873251840)]} 0 0
[junit4] 2> 1441494 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3 (1553737798873251840)]} 0 0
[junit4] 2> 1441496 INFO (qtp2136604218-11699) [n:127.0.0.1:62575_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:62574/solr/collection1_shard1_replica2/&wt=javabin&version=2}{add=[4 (1553737798874300416)]} 0 0
[junit4] 2> 1441497 INFO (qtp325819302-11689) [n:127.0.0.1:62574_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4 (1553737798874300416)]} 0 2
[junit4] 2> 1441498 INFO (qtp325819302-11686) [n:127.0.0.1:62574_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica2] o.a.s.c.S.Request [collection1_shard2_replica2] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1:5&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=http://127.0.0.1:62574/solr/collection1_shard2_replica2/|http://127.0.0.1:62575/solr/collection1_shard2_replica1/&rows=10&version=2&q=*:*&NOW=1481759833219&isShard=true&wt=javabin} hits=0 status=0 QTime=0
[junit4] 2> 1441499 INFO (qtp2136604218-11699) [n:127.0.0.1:62575_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica1] o.a.s.c.S.Request [collection1_shard1_replica1] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1:5&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=http://127.0.0.1:62575/solr/collection1_shard1_replica1/|http://127.0.0.1:62574/solr/collection1_shard1_replica2/&rows=10&version=2&q=*:*&NOW=1481759833219&isShard=true&wt=javabin} hits=0 status=0 QTime=0
[junit4] 2> 1441499 INFO (qtp325819302-11688) [n:127.0.0.1:62574_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica2] o.a.s.c.S.Request [collection1_shard2_replica2] webapp=/solr path=/select params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 1441499 INFO (TEST-TestCloudRecovery.leaderRecoverFromLogOnStartupTest-seed#[D6E6EBE17CC67D26]) [ ] o.a.s.c.ChaosMonkey monkey: stop shard! 62574
[junit4] 2> 1441499 INFO (TEST-TestCloudRecovery.leaderRecoverFromLogOnStartupTest-seed#[D6E6EBE17CC67D26]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=294514027
[junit4] 2> 1441503 INFO (coreCloseExecutor-5386-thread-1) [n:127.0.0.1:62574_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica2] o.a.s.c.SolrCore [collection1_shard1_replica2] CLOSING SolrCore org.apache.solr.core.SolrCore@59bba65f
[junit4] 2> 1441505 INFO (coreCloseExecutor-5386-thread-2) [n:127.0.0.1:62574_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica2] o.a.s.c.SolrCore [collection1_shard2_replica2] CLOSING SolrCore org.apache.solr.core.SolrCore@4e49cd94
[junit4] 2> 1441511 INFO (TEST-TestCloudRecovery.leaderRecoverFromLogOnStartupTest-seed#[D6E6EBE17CC67D26]) [ ] o.a.s.c.Overseer Overseer (id=97108612189913093-127.0.0.1:62574_solr-n_0000000000) closing
[junit4] 2> 1441511 INFO (OverseerStateUpdate-97108612189913093-127.0.0.1:62574_solr-n_0000000000) [n:127.0.0.1:62574_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:62574_solr
[junit4] 2> 1441513 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x158ffc4ae360005, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1441513 WARN (zkCallback-1719-thread-3-processing-n:127.0.0.1:62574_solr) [n:127.0.0.1:62574_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1441514 INFO (TEST-TestCloudRecovery.leaderRecoverFromLogOnStartupTest-seed#[D6E6EBE17CC67D26]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3f38e507{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1441517 INFO (TEST-TestCloudRecovery.leaderRecoverFromLogOnStartupTest-seed#[D6E6EBE17CC67D26]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@527c8184{/solr,null,UNAVAILABLE}
[junit4] 2> 1441518 INFO (zkCallback-1726-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1441520 INFO (zkCallback-1720-thread-2-processing-n:127.0.0.1:62575_solr) [n:127.0.0.1:62575_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1441520 INFO (zkCallback-1720-thread-4-processing-n:127.0.0.1:62575_solr) [n:127.0.0.1:62575_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:62575_solr
[junit4] 2> 1441522 INFO (TEST-TestCloudRecovery.leaderRecoverFromLogOnStartupTest-seed#[D6E6EBE17CC67D26]) [ ] o.a.s.c.ChaosMonkey monkey: stop shard! 62575
[junit4] 2> 1441522 INFO (TEST-TestCloudRecovery.leaderRecoverFromLogOnStartupTest-seed#[D6E6EBE17CC67D26]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1361328183
[junit4] 2> 1441524 INFO (zkCallback-1720-thread-1-processing-n:127.0.0.1:62575_solr) [n:127.0.0.1:62575_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1441524 INFO (zkCallback-1720-thread-3-processing-n:127.0.0.1:62575_solr) [n:127.0.0.1:62575_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1441524 INFO (zkCallback-1720-thread-4-processing-n:127.0.0.1:62575_solr) [n:127.0.0.1:62575_solr ] o.a.s.c.Overseer Overseer (id=97108612189913094-127.0.0.1:62575_solr-n_0000000001) starting
[junit4] 2> 1441532 INFO (TEST-TestCloudRecovery.leaderRecoverFromLogOnStartupTest-seed#[D6E6EBE17CC67D26]) [ ] o.a.s.c.Overseer Overseer (id=97108612189913094-127.0.0.1:62575_solr-n_0000000001) closing
[junit4] 2> 1441533 WARN (zkCallback-1720-thread-4-processing-n:127.0.0.1:62575_solr) [n:127.0.0.1:62575_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1441533 INFO (OverseerStateUpdate-97108612189913094-127.0.0.1:62575_solr-n_0000000001) [n:127.0.0.1:62575_solr ] o.a.s.c.Overseer According to ZK I (id=97108612189913094-127.0.0.1:62575_solr-n_0000000001) am no longer a leader.
[junit4] 2> 1441533 INFO (OverseerCollectionConfigSetProcessor-97108612189913094-127.0.0.1:62575_solr-n_0000000001) [n:127.0.0.1:62575_solr ] o.a.s.c.OverseerTaskProcessor According to ZK I (id=97108612189913094-127.0.0.1:62575_solr-n_0000000001) am no longer a leader.
[junit4] 2> 1441533 ERROR (OverseerCollectionConfigSetProcessor-97108612189913094-127.0.0.1:62575_solr-n_0000000001) [n:127.0.0.1:62575_solr ] o.a.s.c.OverseerTaskProcessor :org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer/collection-queue-work
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1472)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:339)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:336)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:336)
[junit4] 2> at org.apache.solr.cloud.DistributedQueue.fetchZkChildren(DistributedQueue.java:299)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskQueue.getTailId(OverseerTaskQueue.java:264)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor.run(OverseerTaskProcessor.java:162)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 1441533 INFO (OverseerStateUpdate-97108612189913094-127.0.0.1:62575_solr-n_0000000001) [n:127.0.0.1:62575_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:62575_solr
[junit4] 2> 1441535 INFO (zkCallback-1726-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 1444025 WARN (zkCallback-1720-thread-1-processing-n:127.0.0.1:62575_solr) [n:127.0.0.1:62575_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica1] o.a.s.c.SyncStrategy Closed, skipping sync up.
[junit4] 2> 1444025 WARN (zkCallback-1720-thread-3-processing-n:127.0.0.1:62575_solr) [n:127.0.0.1:62575_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica1] o.a.s.c.SyncStrategy Closed, skipping sync up.
[junit4] 2> 1444025 INFO (zkCallback-1720-thread-1-processing-n:127.0.0.1:62575_solr) [n:127.0.0.1:62575_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica1] o.a.s.c.SolrCore [collection1_shard2_replica1] CLOSING SolrCore org.apache.solr.core.SolrCore@3bbe978e
[junit4] 2> 1444025 INFO (zkCallback-1720-thread-3-processing-n:127.0.0.1:62575_solr) [n:127.0.0.1:62575_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica1] o.a.s.c.SolrCore [collection1_shard1_replica1] CLOSING SolrCore org.apache.solr.core.SolrCore@4cefaaf5
[junit4] 2> 1444031 INFO (TEST-TestCloudRecovery.leaderRecoverFromLogOnStartupTest-seed#[D6E6E
[...truncated too long message...]
@6a97b9d0{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 20872 INFO (jetty-launcher-1-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3eefc7c0{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 20874 INFO (jetty-launcher-1-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1648110347
[junit4] 2> 20874 INFO (jetty-launcher-1-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1948145068
[junit4] 2> 20875 INFO (ProcessThread(sid:0 cport:-1):) [ ] o.a.z.s.PrepRequestProcessor Got user-level KeeperException when processing sessionid:0x158ffdde2b20006 type:create cxid:0x121 zxid:0xc9 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4] 2> 20876 INFO (ProcessThread(sid:0 cport:-1):) [ ] o.a.z.s.PrepRequestProcessor Got user-level KeeperException when processing sessionid:0x158ffdde2b20005 type:create cxid:0x1c7 zxid:0xca txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4] 2> 20892 INFO (coreCloseExecutor-25-thread-1) [n:127.0.0.1:57068_solr c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] o.a.s.c.SolrCore [collection1_shard2_replica1] CLOSING SolrCore org.apache.solr.core.SolrCore@16986105
[junit4] 2> 20892 INFO (coreCloseExecutor-26-thread-1) [n:127.0.0.1:57069_solr c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] o.a.s.c.SolrCore [collection1_shard1_replica1] CLOSING SolrCore org.apache.solr.core.SolrCore@1f0f7cbc
[junit4] 2> 20897 INFO (zkCallback-11-thread-1-processing-n:127.0.0.1:57069_solr) [n:127.0.0.1:57069_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 20897 INFO (zkCallback-12-thread-2-processing-n:127.0.0.1:57068_solr) [n:127.0.0.1:57068_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 20972 INFO (ProcessThread(sid:0 cport:-1):) [ ] o.a.z.s.PrepRequestProcessor Processed session termination for sessionid: 0x158ffdde2b20006
[junit4] 2> 20974 INFO (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Closed socket connection for client /127.0.0.1:57091 which had sessionid 0x158ffdde2b20006
[junit4] 2> 20974 INFO (jetty-launcher-1-thread-2) [ ] o.a.z.ZooKeeper Session: 0x158ffdde2b20006 closed
[junit4] 2> 20976 INFO (jetty-launcher-1-thread-1-EventThread) [n:127.0.0.1:57069_solr ] o.a.z.ClientCnxn EventThread shut down
[junit4] 2> 20976 INFO (zkCallback-12-thread-2-processing-n:127.0.0.1:57068_solr) [n:127.0.0.1:57068_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 20979 WARN (zkCallback-11-thread-1-processing-n:127.0.0.1:57069_solr) [n:127.0.0.1:57069_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 20983 INFO (jetty-launcher-1-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@53bee047{/solr,null,UNAVAILABLE}
[junit4] 2> 20988 INFO (jetty-launcher-1-thread-1) [ ] o.a.s.c.Overseer Overseer (id=97108720444637189-127.0.0.1:57068_solr-n_0000000000) closing
[junit4] 2> 20989 INFO (OverseerStateUpdate-97108720444637189-127.0.0.1:57068_solr-n_0000000000) [n:127.0.0.1:57068_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:57068_solr
[junit4] 2> 20992 INFO (ProcessThread(sid:0 cport:-1):) [ ] o.a.z.s.PrepRequestProcessor Processed session termination for sessionid: 0x158ffdde2b20005
[junit4] 2> 20993 INFO (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Closed socket connection for client /127.0.0.1:57090 which had sessionid 0x158ffdde2b20005
[junit4] 2> 20993 INFO (jetty-launcher-1-thread-1) [ ] o.a.z.ZooKeeper Session: 0x158ffdde2b20005 closed
[junit4] 2> 20994 INFO (jetty-launcher-1-thread-2-EventThread) [n:127.0.0.1:57068_solr ] o.a.z.ClientCnxn EventThread shut down
[junit4] 2> 20994 WARN (zkCallback-12-thread-2-processing-n:127.0.0.1:57068_solr) [n:127.0.0.1:57068_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 20995 INFO (jetty-launcher-1-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1ee6c773{/solr,null,UNAVAILABLE}
[junit4] 2> 20996 INFO (SUITE-SolrMorphlineZkAliasTest-seed#[72870D3BD771FC33]-worker) [ ] o.a.z.s.ZooKeeperServer shutting down
[junit4] 2> 20996 INFO (SUITE-SolrMorphlineZkAliasTest-seed#[72870D3BD771FC33]-worker) [ ] o.a.z.s.SessionTrackerImpl Shutting down
[junit4] 2> 20996 INFO (SUITE-SolrMorphlineZkAliasTest-seed#[72870D3BD771FC33]-worker) [ ] o.a.z.s.PrepRequestProcessor Shutting down
[junit4] 2> 20997 INFO (SUITE-SolrMorphlineZkAliasTest-seed#[72870D3BD771FC33]-worker) [ ] o.a.z.s.SyncRequestProcessor Shutting down
[junit4] 2> 20997 INFO (ProcessThread(sid:0 cport:-1):) [ ] o.a.z.s.PrepRequestProcessor PrepRequestProcessor exited loop!
[junit4] 2> 20997 INFO (SyncThread:0) [ ] o.a.z.s.SyncRequestProcessor SyncRequestProcessor exited!
[junit4] 2> 20997 INFO (SUITE-SolrMorphlineZkAliasTest-seed#[72870D3BD771FC33]-worker) [ ] o.a.z.s.FinalRequestProcessor shutdown of request processor complete
[junit4] 2> 20999 INFO (SUITE-SolrMorphlineZkAliasTest-seed#[72870D3BD771FC33]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:57064 57064
[junit4] 2> 21000 INFO (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxnFactory NIOServerCnxn factory exited run method
[junit4] 2> 21000 INFO (Thread-1) [ ] o.a.z.s.ZooKeeperServer shutting down
[junit4] 2> 21000 INFO (Thread-1) [ ] o.a.z.s.SessionTrackerImpl Shutting down
[junit4] 2> 21000 INFO (Thread-1) [ ] o.a.z.s.PrepRequestProcessor Shutting down
[junit4] 2> 21000 INFO (Thread-1) [ ] o.a.z.s.SyncRequestProcessor Shutting down
[junit4] 2> 21000 INFO (Thread-1) [ ] o.a.z.s.FinalRequestProcessor shutdown of request processor complete
[junit4] 2> 21001 INFO (Thread-1) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:57064 57064
[junit4] 2> 21001 INFO (Thread-1) [ ] o.a.z.s.ZooKeeperServer shutting down
[junit4] 2> 21001 INFO (Thread-1) [ ] o.a.z.s.SessionTrackerImpl Shutting down
[junit4] 2> 21001 INFO (Thread-1) [ ] o.a.z.s.PrepRequestProcessor Shutting down
[junit4] 2> 21001 INFO (Thread-1) [ ] o.a.z.s.SyncRequestProcessor Shutting down
[junit4] 2> 21001 INFO (Thread-1) [ ] o.a.z.s.FinalRequestProcessor shutdown of request processor complete
[junit4] 2> 21002 INFO (SUITE-SolrMorphlineZkAliasTest-seed#[72870D3BD771FC33]-worker) [ ] o.a.z.s.ZooKeeperServer shutting down
[junit4] 2> 21002 INFO (SUITE-SolrMorphlineZkAliasTest-seed#[72870D3BD771FC33]-worker) [ ] o.a.z.s.SessionTrackerImpl Shutting down
[junit4] 2> 21002 INFO (SUITE-SolrMorphlineZkAliasTest-seed#[72870D3BD771FC33]-worker) [ ] o.a.z.s.PrepRequestProcessor Shutting down
[junit4] 2> 21002 INFO (SUITE-SolrMorphlineZkAliasTest-seed#[72870D3BD771FC33]-worker) [ ] o.a.z.s.SyncRequestProcessor Shutting down
[junit4] 2> 21002 INFO (SUITE-SolrMorphlineZkAliasTest-seed#[72870D3BD771FC33]-worker) [ ] o.a.z.s.FinalRequestProcessor shutdown of request processor complete
[junit4] 2> 21004 WARN (Thread-1) [ ] 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> 3 /solr/clusterprops.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 2 /solr/collections/collection1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 13 /solr/overseer/queue
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 3 /solr/collections
[junit4] 2> 3 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> 21005 INFO (SUITE-SolrMorphlineZkAliasTest-seed#[72870D3BD771FC33]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-morphlines-core\test\J1\temp\solr.morphlines.solr.SolrMorphlineZkAliasTest_72870D3BD771FC33-001
[junit4] 2> Dec 15, 2016 12:25:02 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 3 leaked thread(s).
[junit4] 2> 21881 INFO (SessionTracker) [ ] o.a.z.s.SessionTrackerImpl SessionTrackerImpl exited loop!
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70), sim=RandomSimilarity(queryNorm=false): {}, locale=vi-VN, timezone=Pacific/Midway
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_112 (64-bit)/cpus=3,threads=1,free=90590712,total=214958080
[junit4] 2> NOTE: All tests run in this JVM: [SolrMorphlineZkAliasTest]
[junit4] Completed [2/4 (1!)] on J1 in 25.02s, 1 test, 1 error <<< FAILURES!
[...truncated 51782 lines...]
[JENKINS] Lucene-Solr-master-Windows (64bit/jdk1.8.0_112) - Build #
6290 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6290/
Java: 64bit/jdk1.8.0_112 -XX:+UseCompressedOops -XX:+UseG1GC
2 tests failed.
FAILED: org.apache.lucene.replicator.http.HttpReplicatorTest.testBasic
Error Message:
Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\replicator\test\J0\temp\lucene.replicator.http.HttpReplicatorTest_5F469629B2DC8AFB-001\httpReplicatorTest-001\2: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\replicator\test\J0\temp\lucene.replicator.http.HttpReplicatorTest_5F469629B2DC8AFB-001\httpReplicatorTest-001\2
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\replicator\test\J0\temp\lucene.replicator.http.HttpReplicatorTest_5F469629B2DC8AFB-001\httpReplicatorTest-001\2: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\replicator\test\J0\temp\lucene.replicator.http.HttpReplicatorTest_5F469629B2DC8AFB-001\httpReplicatorTest-001\2
at __randomizedtesting.SeedInfo.seed([5F469629B2DC8AFB:F4BC8B3C6D000CD5]:0)
at org.apache.lucene.util.IOUtils.rm(IOUtils.java:323)
at org.apache.lucene.replicator.PerSessionDirectoryFactory.cleanupSession(PerSessionDirectoryFactory.java:58)
at org.apache.lucene.replicator.ReplicationClient.doUpdate(ReplicationClient.java:259)
at org.apache.lucene.replicator.ReplicationClient.updateNow(ReplicationClient.java:401)
at org.apache.lucene.replicator.http.HttpReplicatorTest.testBasic(HttpReplicatorTest.java:121)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at 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:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at 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:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.util.TestSolrCLIRunExample.testInteractiveSolrCloudExample
Error Message:
Expected 10 to be found in the testCloudExamplePrompt collection but only found 2
Stack Trace:
java.lang.AssertionError: Expected 10 to be found in the testCloudExamplePrompt collection but only found 2
at __randomizedtesting.SeedInfo.seed([896922F1B3334488:5218C23B844681EE]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.util.TestSolrCLIRunExample.testInteractiveSolrCloudExample(TestSolrCLIRunExample.java:457)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 8272 lines...]
[junit4] Suite: org.apache.lucene.replicator.http.HttpReplicatorTest
[junit4] 2> 2016-12-15 11:28:35.354:INFO::TEST-HttpReplicatorTest.testBasic-seed#[5F469629B2DC8AFB]: Logging initialized @2711ms
[junit4] 2> 2016-12-15 11:28:35.533:INFO:oejs.Server:TEST-HttpReplicatorTest.testBasic-seed#[5F469629B2DC8AFB]: jetty-9.3.14.v20161028
[junit4] 2> 2016-12-15 11:28:35.671:INFO:oejs.AbstractConnector:TEST-HttpReplicatorTest.testBasic-seed#[5F469629B2DC8AFB]: Started ServerConnector@12b9f7ca{HTTP/1.1,[http/1.1]}{127.0.0.1:63667}
[junit4] 2> 2016-12-15 11:28:35.672:INFO:oejs.Server:TEST-HttpReplicatorTest.testBasic-seed#[5F469629B2DC8AFB]: Started @3028ms
[junit4] 2> 2016-12-15 11:28:37.280:INFO:oejs.AbstractConnector:TEST-HttpReplicatorTest.testBasic-seed#[5F469629B2DC8AFB]: Stopped ServerConnector@12b9f7ca{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HttpReplicatorTest -Dtests.method=testBasic -Dtests.seed=5F469629B2DC8AFB -Dtests.slow=true -Dtests.locale=ar-QA -Dtests.timezone=Turkey -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 2.22s J0 | HttpReplicatorTest.testBasic <<<
[junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\replicator\test\J0\temp\lucene.replicator.http.HttpReplicatorTest_5F469629B2DC8AFB-001\httpReplicatorTest-001\2: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\replicator\test\J0\temp\lucene.replicator.http.HttpReplicatorTest_5F469629B2DC8AFB-001\httpReplicatorTest-001\2
[junit4] > at __randomizedtesting.SeedInfo.seed([5F469629B2DC8AFB:F4BC8B3C6D000CD5]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:323)
[junit4] > at org.apache.lucene.replicator.PerSessionDirectoryFactory.cleanupSession(PerSessionDirectoryFactory.java:58)
[junit4] > at org.apache.lucene.replicator.ReplicationClient.doUpdate(ReplicationClient.java:259)
[junit4] > at org.apache.lucene.replicator.ReplicationClient.updateNow(ReplicationClient.java:401)
[junit4] > at org.apache.lucene.replicator.http.HttpReplicatorTest.testBasic(HttpReplicatorTest.java:121)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2016-12-15 11:28:37.380:INFO:oejs.Server:TEST-HttpReplicatorTest.testServerErrors-seed#[5F469629B2DC8AFB]: jetty-9.3.14.v20161028
[junit4] 2> 2016-12-15 11:28:37.383:INFO:oejs.AbstractConnector:TEST-HttpReplicatorTest.testServerErrors-seed#[5F469629B2DC8AFB]: Started ServerConnector@6ff109ad{HTTP/1.1,[http/1.1]}{127.0.0.1:63671}
[junit4] 2> 2016-12-15 11:28:37.383:INFO:oejs.Server:TEST-HttpReplicatorTest.testServerErrors-seed#[5F469629B2DC8AFB]: Started @4740ms
[junit4] 2> 2016-12-15 11:28:37.510:INFO:oejs.AbstractConnector:TEST-HttpReplicatorTest.testServerErrors-seed#[5F469629B2DC8AFB]: Stopped ServerConnector@6ff109ad{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\replicator\test\J0\temp\lucene.replicator.http.HttpReplicatorTest_5F469629B2DC8AFB-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1219, maxMBSortInHeap=5.909918303067825, sim=RandomSimilarity(queryNorm=false): {}, locale=ar-QA, timezone=Turkey
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_112 (64-bit)/cpus=3,threads=1,free=51342048,total=67108864
[junit4] 2> NOTE: All tests run in this JVM: [HttpReplicatorTest]
[junit4] Completed [3/9 (1!)] on J0 in 3.69s, 2 tests, 1 error <<< FAILURES!
[...truncated 3090 lines...]
[junit4] Suite: org.apache.solr.util.TestSolrCLIRunExample
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\init-core-data-001
[junit4] 2> 875802 INFO (SUITE-TestSolrCLIRunExample-seed#[896922F1B3334488]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
[junit4] 2> 875803 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[896922F1B3334488]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testInteractiveSolrCloudExample
[junit4] 2> 878211 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[896922F1B3334488]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002
[junit4] 2> 878212 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[896922F1B3334488]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 878212 INFO (Thread-1902) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 878212 INFO (Thread-1902) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 878312 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[896922F1B3334488]) [ ] o.a.s.c.ZkTestServer start zk server on port:52743
[junit4] 2> 878343 INFO (jetty-launcher-1044-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 878346 INFO (jetty-launcher-1044-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4729299d{/solr,null,AVAILABLE}
[junit4] 2> 878352 INFO (jetty-launcher-1044-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@79bc93c3{HTTP/1.1,[http/1.1]}{127.0.0.1:52735}
[junit4] 2> 878354 INFO (jetty-launcher-1044-thread-1) [ ] o.e.j.s.Server Started @888727ms
[junit4] 2> 878354 INFO (jetty-launcher-1044-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=52735}
[junit4] 2> 878354 ERROR (jetty-launcher-1044-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 878354 INFO (jetty-launcher-1044-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0
[junit4] 2> 878355 INFO (jetty-launcher-1044-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port 52735
[junit4] 2> 878355 INFO (jetty-launcher-1044-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 878355 INFO (jetty-launcher-1044-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-12-15T09:46:26.561Z
[junit4] 2> 878363 INFO (jetty-launcher-1044-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 878456 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15901e02ef80001, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 878467 INFO (jetty-launcher-1044-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52743/solr
[junit4] 2> 878515 INFO (jetty-launcher-1044-thread-1) [n:localhost:52735_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader localhost:52735_solr
[junit4] 2> 878517 INFO (jetty-launcher-1044-thread-1) [n:localhost:52735_solr ] o.a.s.c.Overseer Overseer (id=97110929337483267-localhost:52735_solr-n_0000000000) starting
[junit4] 2> 878529 INFO (jetty-launcher-1044-thread-1) [n:localhost:52735_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/localhost:52735_solr
[junit4] 2> 878535 INFO (zkCallback-1050-thread-1-processing-n:localhost:52735_solr) [n:localhost:52735_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 878668 INFO (jetty-launcher-1044-thread-1) [n:localhost:52735_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1
[junit4] 2> 882071 INFO (qtp701626435-7034) [n:localhost:52735_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=369
[junit4] 2> 882082 INFO (qtp701626435-7034) [n:localhost:52735_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 882084 INFO (qtp701626435-7034) [n:localhost:52735_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=1
[junit4] 2> 882094 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[896922F1B3334488]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 882096 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[896922F1B3334488]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:52743/solr ready
[junit4] 2> 882097 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15901e02ef80005, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 882101 INFO (qtp701626435-7039) [n:localhost:52735_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=list&wt=json and sendToOCPQueue=true
[junit4] 2> 882101 INFO (qtp701626435-7039) [n:localhost:52735_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=list&wt=json} status=0 QTime=0
[junit4] 2> 882124 INFO (qtp701626435-7032) [n:localhost:52735_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=12
[junit4] 2> 882129 INFO (qtp701626435-7034) [n:localhost:52735_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 882131 INFO (qtp701626435-7034) [n:localhost:52735_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=1
[junit4] 2> 882141 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[896922F1B3334488]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 882143 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[896922F1B3334488]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:52743/solr ready
[junit4] 2> 882396 INFO (qtp701626435-7039) [n:localhost:52735_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=list&wt=json and sendToOCPQueue=true
[junit4] 2> 882396 INFO (qtp701626435-7039) [n:localhost:52735_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=list&wt=json} status=0 QTime=0
[junit4] 2> 882401 INFO (qtp701626435-7032) [n:localhost:52735_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&maxShardsPerNode=4&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt&action=CREATE&numShards=2&wt=json and sendToOCPQueue=true
[junit4] 2> 882405 INFO (OverseerThreadFactory-3318-thread-1-processing-n:localhost:52735_solr) [n:localhost:52735_solr ] o.a.s.c.CreateCollectionCmd Create collection testCloudExamplePrompt
[junit4] 2> 882406 WARN (OverseerThreadFactory-3318-thread-1-processing-n:localhost:52735_solr) [n:localhost:52735_solr ] o.a.s.c.CreateCollectionCmd Specified replicationFactor of 2 on collection testCloudExamplePrompt is higher than or equal to the number of Solr instances currently live or live and part of your createNodeSet(1). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 882519 INFO (qtp701626435-7034) [n:localhost:52735_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&newCollection=true&name=testCloudExamplePrompt_shard2_replica1&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard2&wt=javabin&version=2
[junit4] 2> 882532 INFO (qtp701626435-7036) [n:localhost:52735_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&newCollection=true&name=testCloudExamplePrompt_shard1_replica2&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard1&wt=javabin&version=2
[junit4] 2> 882536 INFO (qtp701626435-7038) [n:localhost:52735_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&newCollection=true&name=testCloudExamplePrompt_shard2_replica2&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard2&wt=javabin&version=2
[junit4] 2> 882536 INFO (qtp701626435-7039) [n:localhost:52735_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&newCollection=true&name=testCloudExamplePrompt_shard1_replica1&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard1&wt=javabin&version=2
[junit4] 2> 882656 INFO (zkCallback-1050-thread-2-processing-n:localhost:52735_solr) [n:localhost:52735_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCloudExamplePrompt/state.json] for collection [testCloudExamplePrompt] has occurred - updating... (live nodes size: [1])
[junit4] 2> 883579 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 883579 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 883582 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 883582 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 883582 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 883582 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 883591 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 883591 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 883594 WARN (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\contrib\extraction\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\contrib\extraction\lib
[junit4] 2> 883595 WARN (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica2\..\..\..\..\contrib\extraction\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica2\..\..\..\..\contrib\extraction\lib
[junit4] 2> 883595 WARN (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\contrib\extraction\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\contrib\extraction\lib
[junit4] 2> 883596 WARN (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\contrib\extraction\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\contrib\extraction\lib
[junit4] 2> 883597 WARN (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\dist filtered by solr-cell-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\dist
[junit4] 2> 883597 WARN (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica2\..\..\..\..\dist filtered by solr-cell-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica2\..\..\..\..\dist
[junit4] 2> 883598 WARN (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\dist filtered by solr-cell-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\dist
[junit4] 2> 883598 WARN (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\dist filtered by solr-cell-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\dist
[junit4] 2> 883600 WARN (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica2\..\..\..\..\contrib\clustering\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica2\..\..\..\..\contrib\clustering\lib
[junit4] 2> 883601 WARN (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\contrib\clustering\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\contrib\clustering\lib
[junit4] 2> 883601 WARN (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\contrib\clustering\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\contrib\clustering\lib
[junit4] 2> 883601 WARN (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica2\..\..\..\..\dist filtered by solr-clustering-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica2\..\..\..\..\dist
[junit4] 2> 883601 WARN (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\dist filtered by solr-clustering-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\dist
[junit4] 2> 883601 WARN (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\dist filtered by solr-clustering-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\dist
[junit4] 2> 883602 WARN (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\contrib\clustering\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\contrib\clustering\lib
[junit4] 2> 883603 WARN (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\dist filtered by solr-clustering-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\dist
[junit4] 2> 883604 WARN (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\contrib\langid\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\contrib\langid\lib
[junit4] 2> 883604 WARN (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\contrib\langid\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\contrib\langid\lib
[junit4] 2> 883605 WARN (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\dist filtered by solr-langid-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\dist
[junit4] 2> 883605 WARN (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\dist filtered by solr-langid-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\dist
[junit4] 2> 883606 WARN (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica2\..\..\..\..\contrib\langid\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica2\..\..\..\..\contrib\langid\lib
[junit4] 2> 883606 WARN (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\contrib\langid\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\contrib\langid\lib
[junit4] 2> 883606 WARN (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica2\..\..\..\..\dist filtered by solr-langid-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica2\..\..\..\..\dist
[junit4] 2> 883606 WARN (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\dist filtered by solr-langid-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\dist
[junit4] 2> 883609 WARN (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\contrib\velocity\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\contrib\velocity\lib
[junit4] 2> 883609 WARN (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\contrib\velocity\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\contrib\velocity\lib
[junit4] 2> 883610 WARN (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\dist filtered by solr-velocity-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\dist
[junit4] 2> 883610 WARN (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\dist filtered by solr-velocity-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\dist
[junit4] 2> 883622 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 883637 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 883657 WARN (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\contrib\velocity\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\contrib\velocity\lib
[junit4] 2> 883658 WARN (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica2\..\..\..\..\contrib\velocity\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica2\..\..\..\..\contrib\velocity\lib
[junit4] 2> 883658 WARN (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica2\..\..\..\..\dist filtered by solr-velocity-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica2\..\..\..\..\dist
[junit4] 2> 883662 WARN (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\dist filtered by solr-velocity-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\dist
[junit4] 2> 883669 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 883674 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 883677 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.s.IndexSchema [testCloudExamplePrompt_shard1_replica1] Schema name=example-data-driven-schema
[junit4] 2> 883701 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.s.IndexSchema [testCloudExamplePrompt_shard1_replica2] Schema name=example-data-driven-schema
[junit4] 2> 883706 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.s.IndexSchema [testCloudExamplePrompt_shard2_replica1] Schema name=example-data-driven-schema
[junit4] 2> 883714 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.s.IndexSchema [testCloudExamplePrompt_shard2_replica2] Schema name=example-data-driven-schema
[junit4] 2> 884498 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.s.IndexSchema Loaded schema example-data-driven-schema/1.6 with uniqueid field id
[junit4] 2> 884499 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.s.IndexSchema Loaded schema example-data-driven-schema/1.6 with uniqueid field id
[junit4] 2> 884500 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.s.IndexSchema Loaded schema example-data-driven-schema/1.6 with uniqueid field id
[junit4] 2> 884500 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.s.IndexSchema Loaded schema example-data-driven-schema/1.6 with uniqueid field id
[junit4] 2> 884742 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.CoreContainer Creating SolrCore 'testCloudExamplePrompt_shard2_replica2' using configuration from collection testCloudExamplePrompt
[junit4] 2> 884743 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrCore [[testCloudExamplePrompt_shard2_replica2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica2\data\]
[junit4] 2> 884743 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7accbfaf
[junit4] 2> 884752 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.CoreContainer Creating SolrCore 'testCloudExamplePrompt_shard2_replica1' using configuration from collection testCloudExamplePrompt
[junit4] 2> 884757 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrCore [[testCloudExamplePrompt_shard2_replica1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\data\]
[junit4] 2> 884757 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7accbfaf
[junit4] 2> 884762 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.CoreContainer Creating SolrCore 'testCloudExamplePrompt_shard1_replica1' using configuration from collection testCloudExamplePrompt
[junit4] 2> 884762 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrCore [[testCloudExamplePrompt_shard1_replica1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\data\]
[junit4] 2> 884762 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7accbfaf
[junit4] 2> 884767 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.CoreContainer Creating SolrCore 'testCloudExamplePrompt_shard1_replica2' using configuration from collection testCloudExamplePrompt
[junit4] 2> 884768 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrCore [[testCloudExamplePrompt_shard1_replica2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\data\]
[junit4] 2> 884768 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7accbfaf
[junit4] 2> 884809 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 884809 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 884810 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 884810 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 885711 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 885711 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 885712 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 885712 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 885717 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 885717 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 885718 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 885718 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 885718 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 885719 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 885719 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 885719 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 885721 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.s.SolrIndexSearcher Opening [Searcher@40c7025c[testCloudExamplePrompt_shard1_replica2] main]
[junit4] 2> 885723 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 885724 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 885724 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 885724 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 885724 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testCloudExamplePrompt
[junit4] 2> 885725 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testCloudExamplePrompt
[junit4] 2> 885725 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testCloudExamplePrompt/managed-schema
[junit4] 2> 885725 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.s.SolrIndexSearcher Opening [Searcher@35e82ad5[testCloudExamplePrompt_shard2_replica2] main]
[junit4] 2> 885725 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@b7641a5[testCloudExamplePrompt_shard2_replica1] main]
[junit4] 2> 885726 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@344ac150[testCloudExamplePrompt_shard1_replica1] main]
[junit4] 2> 885726 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 885726 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 885727 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testCloudExamplePrompt
[junit4] 2> 885728 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testCloudExamplePrompt
[junit4] 2> 885728 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testCloudExamplePrompt/managed-schema
[junit4] 2> 885729 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testCloudExamplePrompt
[junit4] 2> 885729 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testCloudExamplePrompt
[junit4] 2> 885729 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testCloudExamplePrompt
[junit4] 2> 885730 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testCloudExamplePrompt/managed-schema
[junit4] 2> 885730 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 885730 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 885730 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testCloudExamplePrompt
[junit4] 2> 885731 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testCloudExamplePrompt/managed-schema
[junit4] 2> 885732 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 885732 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 885733 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 885734 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 885807 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] 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> 885810 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] 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> 885810 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] 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> 885812 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] 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> 885884 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 885884 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 885884 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 885886 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1553774877440212992
[junit4] 2> 885886 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1553774877440212992
[junit4] 2> 885886 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1553774877440212992
[junit4] 2> 885890 INFO (searcherExecutor-3322-thread-1-processing-n:localhost:52735_solr x:testCloudExamplePrompt_shard2_replica1 s:shard2 c:testCloudExamplePrompt) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@b7641a5[testCloudExamplePrompt_shard2_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 885890 INFO (searcherExecutor-3322-thread-1-processing-n:localhost:52735_solr x:testCloudExamplePrompt_shard2_replica1 s:shard2 c:testCloudExamplePrompt) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 885890 INFO (searcherExecutor-3322-thread-1-processing-n:localhost:52735_solr x:testCloudExamplePrompt_shard2_replica1 s:shard2 c:testCloudExamplePrompt) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 885891 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 885891 INFO (searcherExecutor-3322-thread-1-processing-n:localhost:52735_solr x:testCloudExamplePrompt_shard2_replica1 s:shard2 c:testCloudExamplePrompt) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrCore [testCloudExamplePrompt_shard2_replica1] Registered new searcher Searcher@b7641a5[testCloudExamplePrompt_shard2_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 885891 INFO (searcherExecutor-3324-thread-1-processing-n:localhost:52735_solr x:testCloudExamplePrompt_shard1_replica2 s:shard1 c:testCloudExamplePrompt) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@40c7025c[testCloudExamplePrompt_shard1_replica2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 885893 INFO (searcherExecutor-3321-thread-1-processing-n:localhost:52735_solr x:testCloudExamplePrompt_shard2_replica2 s:shard2 c:testCloudExamplePrompt) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@35e82ad5[testCloudExamplePrompt_shard2_replica2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 885893 INFO (searcherExecutor-3321-thread-1-processing-n:localhost:52735_solr x:testCloudExamplePrompt_shard2_replica2 s:shard2 c:testCloudExamplePrompt) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 885893 INFO (searcherExecutor-3321-thread-1-processing-n:localhost:52735_solr x:testCloudExamplePrompt_shard2_replica2 s:shard2 c:testCloudExamplePrompt) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 885894 INFO (searcherExecutor-3321-thread-1-processing-n:localhost:52735_solr x:testCloudExamplePrompt_shard2_replica2 s:shard2 c:testCloudExamplePrompt) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrCore [testCloudExamplePrompt_shard2_replica2] Registered new searcher Searcher@35e82ad5[testCloudExamplePrompt_shard2_replica2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 885893 INFO (searcherExecutor-3324-thread-1-processing-n:localhost:52735_solr x:testCloudExamplePrompt_shard1_replica2 s:shard1 c:testCloudExamplePrompt) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 885894 INFO (searcherExecutor-3324-thread-1-processing-n:localhost:52735_solr x:testCloudExamplePrompt_shard1_replica2 s:shard1 c:testCloudExamplePrompt) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 885895 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1553774877449650176
[junit4] 2> 885895 INFO (searcherExecutor-3324-thread-1-processing-n:localhost:52735_solr x:testCloudExamplePrompt_shard1_replica2 s:shard1 c:testCloudExamplePrompt) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrCore [testCloudExamplePrompt_shard1_replica2] Registered new searcher Searcher@40c7025c[testCloudExamplePrompt_shard1_replica2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 885893 INFO (searcherExecutor-3323-thread-1-processing-n:localhost:52735_solr x:testCloudExamplePrompt_shard1_replica1 s:shard1 c:testCloudExamplePrompt) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@344ac150[testCloudExamplePrompt_shard1_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 885897 INFO (searcherExecutor-3323-thread-1-processing-n:localhost:52735_solr x:testCloudExamplePrompt_shard1_replica1 s:shard1 c:testCloudExamplePrompt) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 885897 INFO (searcherExecutor-3323-thread-1-processing-n:localhost:52735_solr x:testCloudExamplePrompt_shard1_replica1 s:shard1 c:testCloudExamplePrompt) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 885897 INFO (searcherExecutor-3323-thread-1-processing-n:localhost:52735_solr x:testCloudExamplePrompt_shard1_replica1 s:shard1 c:testCloudExamplePrompt) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrCore [testCloudExamplePrompt_shard1_replica1] Registered new searcher Searcher@344ac150[testCloudExamplePrompt_shard1_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 885911 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 885911 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 885911 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SyncStrategy Sync replicas to http://localhost:52735/solr/testCloudExamplePrompt_shard1_replica1/
[junit4] 2> 885911 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.u.PeerSync PeerSync: core=testCloudExamplePrompt_shard1_replica1 url=http://localhost:52735/solr START replicas=[http://localhost:52735/solr/testCloudExamplePrompt_shard1_replica2/] nUpdates=100
[junit4] 2> 885912 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 885912 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 885912 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SyncStrategy Sync replicas to http://localhost:52735/solr/testCloudExamplePrompt_shard2_replica2/
[junit4] 2> 885912 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.u.PeerSync PeerSync: core=testCloudExamplePrompt_shard2_replica2 url=http://localhost:52735/solr START replicas=[http://localhost:52735/solr/testCloudExamplePrompt_shard2_replica1/] nUpdates=100
[junit4] 2> 885912 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.u.PeerSync PeerSync: core=testCloudExamplePrompt_shard1_replica1 url=http://localhost:52735/solr DONE. We have no versions. sync failed.
[junit4] 2> 885912 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.u.PeerSync PeerSync: core=testCloudExamplePrompt_shard2_replica2 url=http://localhost:52735/solr DONE. We have no versions. sync failed.
[junit4] 2> 885914 INFO (zkCallback-1050-thread-1-processing-n:localhost:52735_solr) [n:localhost:52735_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCloudExamplePrompt/state.json] for collection [testCloudExamplePrompt] has occurred - updating... (live nodes size: [1])
[junit4] 2> 885920 INFO (qtp701626435-7037) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 r:core_node2 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.S.Request [testCloudExamplePrompt_shard1_replica2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 885921 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 885921 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 885922 INFO (qtp701626435-7035) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 r:core_node1 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.S.Request [testCloudExamplePrompt_shard2_replica1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 885922 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 885922 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 885968 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://localhost:52735/solr/testCloudExamplePrompt_shard2_replica2/ shard2
[junit4] 2> 885968 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://localhost:52735/solr/testCloudExamplePrompt_shard1_replica1/ shard1
[junit4] 2> 886084 INFO (zkCallback-1050-thread-2-processing-n:localhost:52735_solr) [n:localhost:52735_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCloudExamplePrompt/state.json] for collection [testCloudExamplePrompt] has occurred - updating... (live nodes size: [1])
[junit4] 2> 886123 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 886123 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 886132 INFO (qtp701626435-7039) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=testCloudExamplePrompt&newCollection=true&name=testCloudExamplePrompt_shard1_replica1&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard1&wt=javabin&version=2} status=0 QTime=3596
[junit4] 2> 886139 INFO (qtp701626435-7038) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=testCloudExamplePrompt&newCollection=true&name=testCloudExamplePrompt_shard2_replica2&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard2&wt=javabin&version=2} status=0 QTime=3603
[junit4] 2> 886242 INFO (zkCallback-1050-thread-3-processing-n:localhost:52735_solr) [n:localhost:52735_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCloudExamplePrompt/state.json] for collection [testCloudExamplePrompt] has occurred - updating... (live nodes size: [1])
[junit4] 2> 886916 INFO (qtp701626435-7036) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=testCloudExamplePrompt&newCollection=true&name=testCloudExamplePrompt_shard1_replica2&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard1&wt=javabin&version=2} status=0 QTime=4384
[junit4] 2> 886917 INFO (qtp701626435-7034) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=testCloudExamplePrompt&newCollection=true&name=testCloudExamplePrompt_shard2_replica1&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard2&wt=javabin&version=2} status=0 QTime=4398
[junit4] 2> 886921 INFO (qtp701626435-7032) [n:localhost:52735_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> 887020 INFO (zkCallback-1050-thread-1-processing-n:localhost:52735_solr) [n:localhost:52735_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCloudExamplePrompt/state.json] for collection [testCloudExamplePrompt] has occurred - updating... (live nodes size: [1])
[junit4] 2> 887923 INFO (qtp701626435-7032) [n:localhost:52735_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=2&maxShardsPerNode=4&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt&action=CREATE&numShards=2&wt=json} status=0 QTime=5522
[junit4] 2> 887947 INFO (qtp701626435-7037) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 r:core_node3 x:testCloudExamplePrompt_shard1_replica1] o.a.s.h.SolrConfigHandler Executed config commands successfully and persisted to ZK [{"set-property":{"updateHandler.autoSoftCommit.maxTime":"3000"}}]
[junit4] 2> 887947 INFO (qtp701626435-7037) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 r:core_node3 x:testCloudExamplePrompt_shard1_replica1] o.a.s.h.SolrConfigHandler Waiting up to 30 secs for 4 replicas to set the property overlay to be of version 0 for collection testCloudExamplePrompt
[junit4] 2> 887948 INFO (Thread-1915) [n:localhost:52735_solr ] o.a.s.c.SolrCore config update listener called for core testCloudExamplePrompt_shard1_replica2
[junit4] 2> 887950 INFO (Thread-1915) [n:localhost:52735_solr ] o.a.s.c.SolrCore core reload testCloudExamplePrompt_shard1_replica2
[junit4] 2> 887950 INFO (solrHandlerExecutor-3341-thread-1-processing-n:localhost:52735_solr x:testCloudExamplePrompt_shard1_replica1 s:shard1 c:testCloudExamplePrompt r:core_node3) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 r:core_node3 x:testCloudExamplePrompt_shard1_replica1] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 887950 INFO (solrHandlerExecutor-3341-thread-3-processing-n:localhost:52735_solr x:testCloudExamplePrompt_shard1_replica1 s:shard1 c:testCloudExamplePrompt r:core_node3) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 r:core_node3 x:testCloudExamplePrompt_shard1_replica1] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 887950 INFO (solrHandlerExecutor-3341-thread-2-processing-n:localhost:52735_solr x:testCloudExamplePrompt_shard1_replica1 s:shard1 c:testCloudExamplePrompt r:core_node3) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 r:core_node3 x:testCloudExamplePrompt_shard1_replica1] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 887952 INFO (solrHandlerExecutor-3341-thread-4-processing-n:localhost:52735_solr x:testCloudExamplePrompt_shard1_replica1 s:shard1 c:testCloudExamplePrompt r:core_node3) [n:localhost:52735_solr c:testCloudExamplePrompt s:shard1 r:core_node3 x:testCloudExamplePrompt_shard1_replica1] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 887971 INFO (Thread-1915) [n:localhost:52735_solr ] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 887971 INFO (Thread-1915) [n:localhost:52735_solr ] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 887973 WARN (Thread-1915) [n:localhost:52735_solr ] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\contrib\extraction\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\contrib\extraction\lib
[junit4] 2> 887974 WARN (Thread-1915) [n:localhost:52735_solr ] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\dist filtered by solr-cell-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\dist
[junit4] 2> 887976 WARN (Thread-1915) [n:localhost:52735_solr ] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\contrib\clustering\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\contrib\clustering\lib
[junit4] 2> 887976 WARN (Thread-1915) [n:localhost:52735_solr ] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lu
[...truncated too long message...]
filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-003\schemaless\solr\gettingstarted\..\..\..\..\contrib\velocity\lib
[junit4] 2> 893897 WARN (qtp1029568794-7185) [ x:gettingstarted] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-003\schemaless\solr\gettingstarted\..\..\..\..\dist filtered by solr-velocity-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-003\schemaless\solr\gettingstarted\..\..\..\..\dist
[junit4] 2> 893905 INFO (qtp1029568794-7185) [ x:gettingstarted] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 893942 INFO (qtp1029568794-7185) [ x:gettingstarted] o.a.s.s.IndexSchema [gettingstarted] Schema name=example-data-driven-schema
[junit4] 2> 894120 INFO (qtp1029568794-7185) [ x:gettingstarted] o.a.s.s.IndexSchema Loaded schema example-data-driven-schema/1.6 with uniqueid field id
[junit4] 2> 894153 INFO (qtp1029568794-7185) [ x:gettingstarted] o.a.s.c.CoreContainer Creating SolrCore 'gettingstarted' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-003\schemaless\solr\gettingstarted
[junit4] 2> 894154 INFO (qtp1029568794-7185) [ x:gettingstarted] o.a.s.c.SolrCore [[gettingstarted] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-003\schemaless\solr\gettingstarted], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-003\schemaless\solr\gettingstarted\data\]
[junit4] 2> 894154 INFO (qtp1029568794-7185) [ x:gettingstarted] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7accbfaf
[junit4] 2> 894163 INFO (qtp1029568794-7185) [ x:gettingstarted] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 894190 INFO (qtp1029568794-7185) [ x:gettingstarted] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 894190 INFO (qtp1029568794-7185) [ x:gettingstarted] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 894193 INFO (qtp1029568794-7185) [ x:gettingstarted] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 894193 INFO (qtp1029568794-7185) [ x:gettingstarted] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 894193 INFO (qtp1029568794-7185) [ x:gettingstarted] o.a.s.s.SolrIndexSearcher Opening [Searcher@5cf86583[gettingstarted] main]
[junit4] 2> 894195 INFO (qtp1029568794-7185) [ x:gettingstarted] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-003\schemaless\solr\gettingstarted\conf
[junit4] 2> 894196 INFO (qtp1029568794-7185) [ x:gettingstarted] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 894197 INFO (qtp1029568794-7185) [ x:gettingstarted] 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> 894199 INFO (qtp1029568794-7185) [ x:gettingstarted] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001\tempDir-003\schemaless\solr\gettingstarted\conf\elevate.xml
[junit4] 2> 894206 INFO (qtp1029568794-7185) [ x:gettingstarted] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 894206 INFO (searcherExecutor-3374-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@5cf86583[gettingstarted] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 894206 INFO (searcherExecutor-3374-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 894206 INFO (searcherExecutor-3374-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 894207 INFO (searcherExecutor-3374-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.c.SolrCore [gettingstarted] Registered new searcher Searcher@5cf86583[gettingstarted] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 894208 INFO (qtp1029568794-7185) [ x:gettingstarted] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1553774886166462464
[junit4] 2> 894213 INFO (qtp1029568794-7185) [ x:gettingstarted] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={name=gettingstarted&action=CREATE&instanceDir=gettingstarted&wt=json} status=0 QTime=368
[junit4] 2> 894217 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[896922F1B3334488]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3e481615{HTTP/1.1,[http/1.1]}{127.0.0.1:52830}
[junit4] 2> 894217 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[896922F1B3334488]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1139606756
[junit4] 2> 894218 INFO (coreCloseExecutor-3379-thread-1) [ x:gettingstarted] o.a.s.c.SolrCore [gettingstarted] CLOSING SolrCore org.apache.solr.core.SolrCore@4305a3d
[junit4] 2> 894230 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[896922F1B3334488]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3b61445e{/solr,null,UNAVAILABLE}
[junit4] 2> 894232 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[896922F1B3334488]) [ ] o.a.s.u.TestSolrCLIRunExample Stopped standalone Solr instance running on port 52830
[junit4] 2> 894232 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[896922F1B3334488]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSchemalessExample
[junit4] 2> 894232 INFO (SUITE-TestSolrCLIRunExample-seed#[896922F1B3334488]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.util.TestSolrCLIRunExample_896922F1B3334488-001
[junit4] 2> NOTE: test params are: codec=Lucene70, sim=RandomSimilarity(queryNorm=true): {}, locale=ko-KR, timezone=America/Caracas
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_112 (64-bit)/cpus=3,threads=1,free=310901272,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [DeleteReplicaTest, TestFileDictionaryLookup, BasicDistributedZkTest, DirectUpdateHandlerTest, TestSimpleQParserPlugin, SolrCLIZkUtilsTest, TestXmlQParserPlugin, DocumentAnalysisRequestHandlerTest, TestBinaryField, DistributedFacetPivotSmallAdvancedTest, TestFunctionQuery, TestReplicationHandler, TestPartialUpdateDeduplication, StandardRequestHandlerTest, SpatialFilterTest, DirectoryFactoryTest, TestFieldCacheSort, CloneFieldUpdateProcessorFactoryTest, TestSweetSpotSimilarityFactory, TestDistributedStatsComponentCardinality, PeerSyncTest, AssignTest, SimpleFacetsTest, SolrPluginUtilsTest, TestFastLRUCache, TestMissingGroups, TestPivotHelperCode, OutOfBoxZkACLAndCredentialsProvidersTest, ChangedSchemaMergeTest, OutputWriterTest, MBeansHandlerTest, ZkCLITest, BadCopyFieldTest, TestImplicitCoreProperties, TestCoreContainer, TestCharFilters, TestUpdate, CdcrUpdateLogTest, TestOrdValues, TestSolrCloudWithKerberosAlt, TestCollectionAPI, TestJettySolrRunner, TestBackupRepositoryFactory, AnalysisAfterCoreReloadTest, RequiredFieldsTest, ClusterStateUpdateTest, QueryElevationComponentTest, ConnectionManagerTest, DistributedMLTComponentTest, RecoveryAfterSoftCommitTest, DistribDocExpirationUpdateProcessorTest, TestDocTermOrds, CursorPagingTest, CollectionStateFormat2Test, TestUseDocValuesAsStored, DOMUtilTest, TestHighFrequencyDictionaryFactory, HdfsDirectoryTest, TestReqParamsAPI, RestartWhileUpdatingTest, StatsReloadRaceTest, TestSubQueryTransformerDistrib, PeerSyncWithIndexFingerprintCachingTest, TestIntervalFaceting, VersionInfoTest, TestLFUCache, SampleTest, FileUtilsTest, TestExactStatsCache, ChaosMonkeyNothingIsSafeTest, TestPhraseSuggestions, HdfsDirectoryFactoryTest, DocValuesMissingTest, CloudMLTQParserTest, TestGroupingSearch, TestFieldCollectionResource, TestCSVResponseWriter, TestSort, DeleteStatusTest, TestCSVLoader, IgnoreCommitOptimizeUpdateProcessorFactoryTest, UUIDUpdateProcessorFallbackTest, ReplaceNodeTest, TestTolerantSearch, TestCustomDocTransformer, TestStressLucene, PathHierarchyTokenizerFactoryTest, LeaderInitiatedRecoveryOnCommitTest, NumericFieldsTest, TestSolrXml, TestFilteredDocIdSet, TestStressCloudBlindAtomicUpdates, ShardRoutingTest, TestSchemaNameResource, TestSolrCLIRunExample]
[junit4] Completed [210/660 (1!)] on J0 in 18.46s, 3 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 63804 lines...]