Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/783/ Java: 32bit/jdk1.8.0_144 -server -XX:+UseG1GC
3 tests failed. FAILED: org.apache.solr.client.solrj.impl.CloudSolrClientTest.testRetryUpdatesWhenClusterStateIsStale Error Message: Error from server at https://127.0.0.1:38643/solr/stale_state_test_col_shard1_replica_n1: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 404 </title> </head> <body> <h2>HTTP ERROR: 404</h2> <p>Problem accessing /solr/stale_state_test_col_shard1_replica_n1/update. Reason: <pre> Can not find: /solr/stale_state_test_col_shard1_replica_n1/update</pre></p> <hr /><a href="http://eclipse.org/jetty";>Powered by Jetty:// 9.3.20.v20170531</a><hr/> </body> </html> Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:38643/solr/stale_state_test_col_shard1_replica_n1: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 404 </title> </head> <body> <h2>HTTP ERROR: 404</h2> <p>Problem accessing /solr/stale_state_test_col_shard1_replica_n1/update. Reason: <pre> Can not find: /solr/stale_state_test_col_shard1_replica_n1/update</pre></p> <hr /><a href="http://eclipse.org/jetty";>Powered by Jetty:// 9.3.20.v20170531</a><hr/> </body> </html> at __randomizedtesting.SeedInfo.seed([1D670CA03ECF9456:A9569448DD26E27A]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:607) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413) at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:559) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1016) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:883) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:816) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) at org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:233) at org.apache.solr.client.solrj.impl.CloudSolrClientTest.testRetryUpdatesWhenClusterStateIsStale(CloudSolrClientTest.java:844) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at org.junit.rules.ExpectedException$ExpectedExceptionStatement.evaluate(ExpectedException.java:110) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.cloud.autoscaling.HdfsAutoAddReplicasIntegrationTest.testSimple Error Message: Waiting for collection testSimple2 null Live Nodes: [127.0.0.1:38501_solr, 127.0.0.1:43915_solr] Last available state: DocCollection(testSimple2//collections/testSimple2/state.json/21)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-ffffffff", "state":"active", "replicas":{ "core_node3":{ "dataDir":"hdfs://localhost.localdomain:33785/data/testSimple2/core_node3/data/", "base_url":"https://127.0.0.1:45301/solr";, "node_name":"127.0.0.1:45301_solr", "type":"NRT", "ulogDir":"hdfs://localhost.localdomain:33785/data/testSimple2/core_node3/data/tlog", "core":"testSimple2_shard1_replica_n1", "state":"down"}, "core_node5":{ "dataDir":"hdfs://localhost.localdomain:33785/data/testSimple2/core_node5/data/", "base_url":"https://127.0.0.1:38501/solr";, "node_name":"127.0.0.1:38501_solr", "type":"NRT", "ulogDir":"hdfs://localhost.localdomain:33785/data/testSimple2/core_node5/data/tlog", "core":"testSimple2_shard1_replica_n2", "state":"active", "leader":"true"}}}, "shard2":{ "range":"0-7fffffff", "state":"active", "replicas":{ "core_node7":{ "dataDir":"hdfs://localhost.localdomain:33785/data/testSimple2/core_node7/data/", "base_url":"https://127.0.0.1:45301/solr";, "node_name":"127.0.0.1:45301_solr", "type":"NRT", "ulogDir":"hdfs://localhost.localdomain:33785/data/testSimple2/core_node7/data/tlog", "core":"testSimple2_shard2_replica_n4", "state":"down"}, "core_node8":{ "dataDir":"hdfs://localhost.localdomain:33785/data/testSimple2/core_node8/data/", "base_url":"https://127.0.0.1:38501/solr";, "node_name":"127.0.0.1:38501_solr", "type":"NRT", "ulogDir":"hdfs://localhost.localdomain:33785/data/testSimple2/core_node8/data/tlog", "core":"testSimple2_shard2_replica_n6", "state":"active", "leader":"true"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"true", "nrtReplicas":"2", "tlogReplicas":"0"} Stack Trace: java.lang.AssertionError: Waiting for collection testSimple2 null Live Nodes: [127.0.0.1:38501_solr, 127.0.0.1:43915_solr] Last available state: DocCollection(testSimple2//collections/testSimple2/state.json/21)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-ffffffff", "state":"active", "replicas":{ "core_node3":{ "dataDir":"hdfs://localhost.localdomain:33785/data/testSimple2/core_node3/data/", "base_url":"https://127.0.0.1:45301/solr";, "node_name":"127.0.0.1:45301_solr", "type":"NRT", "ulogDir":"hdfs://localhost.localdomain:33785/data/testSimple2/core_node3/data/tlog", "core":"testSimple2_shard1_replica_n1", "state":"down"}, "core_node5":{ "dataDir":"hdfs://localhost.localdomain:33785/data/testSimple2/core_node5/data/", "base_url":"https://127.0.0.1:38501/solr";, "node_name":"127.0.0.1:38501_solr", "type":"NRT", "ulogDir":"hdfs://localhost.localdomain:33785/data/testSimple2/core_node5/data/tlog", "core":"testSimple2_shard1_replica_n2", "state":"active", "leader":"true"}}}, "shard2":{ "range":"0-7fffffff", "state":"active", "replicas":{ "core_node7":{ "dataDir":"hdfs://localhost.localdomain:33785/data/testSimple2/core_node7/data/", "base_url":"https://127.0.0.1:45301/solr";, "node_name":"127.0.0.1:45301_solr", "type":"NRT", "ulogDir":"hdfs://localhost.localdomain:33785/data/testSimple2/core_node7/data/tlog", "core":"testSimple2_shard2_replica_n4", "state":"down"}, "core_node8":{ "dataDir":"hdfs://localhost.localdomain:33785/data/testSimple2/core_node8/data/", "base_url":"https://127.0.0.1:38501/solr";, "node_name":"127.0.0.1:38501_solr", "type":"NRT", "ulogDir":"hdfs://localhost.localdomain:33785/data/testSimple2/core_node8/data/tlog", "core":"testSimple2_shard2_replica_n6", "state":"active", "leader":"true"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"true", "nrtReplicas":"2", "tlogReplicas":"0"} at __randomizedtesting.SeedInfo.seed([1878EB209465F1E2:20CBCFDEB3962533]:0) at org.junit.Assert.fail(Assert.java:93) at org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:269) at org.apache.solr.cloud.autoscaling.AutoAddReplicasIntegrationTest.testSimple(AutoAddReplicasIntegrationTest.java:125) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigReplication Error Message: Index: 0, Size: 0 Stack Trace: java.lang.IndexOutOfBoundsException: Index: 0, Size: 0 at __randomizedtesting.SeedInfo.seed([1878EB209465F1E2:C30B075B7624CFC]:0) at java.util.ArrayList.rangeCheck(ArrayList.java:653) at java.util.ArrayList.get(ArrayList.java:429) at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigReplication(TestReplicationHandler.java:561) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 13293 lines...] [junit4] Suite: org.apache.solr.handler.TestReplicationHandler [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/init-core-data-001 [junit4] 2> 2386258 WARN (SUITE-TestReplicationHandler-seed#[1878EB209465F1E2]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=16 numCloses=16 [junit4] 2> 2386258 INFO (SUITE-TestReplicationHandler-seed#[1878EB209465F1E2]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 2386259 INFO (SUITE-TestReplicationHandler-seed#[1878EB209465F1E2]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None) [junit4] 2> 2386259 INFO (SUITE-TestReplicationHandler-seed#[1878EB209465F1E2]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 2386261 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestStressReplication [junit4] 2> 2386262 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-001/collection1 [junit4] 2> 2386265 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 2386266 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@557d2b{/solr,null,AVAILABLE} [junit4] 2> 2386269 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7dd735{HTTP/1.1,[http/1.1]}{127.0.0.1:35127} [junit4] 2> 2386269 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.e.j.s.Server Started @2388153ms [junit4] 2> 2386269 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=35127} [junit4] 2> 2386270 ERROR (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2386270 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.2.0 [junit4] 2> 2386270 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 2386270 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2386270 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-11-10T01:02:50.970Z [junit4] 2> 2386270 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-001/solr.xml [junit4] 2> 2386273 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 2386273 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 2386273 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@17e306f, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 2386300 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17e306f [junit4] 2> 2386308 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17e306f [junit4] 2> 2386308 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17e306f [junit4] 2> 2386310 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-001/. [junit4] 2> 2386310 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 2386310 INFO (coreLoadExecutor-4074-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 2386317 INFO (coreLoadExecutor-4074-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0 [junit4] 2> 2386321 INFO (coreLoadExecutor-4074-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2386323 INFO (coreLoadExecutor-4074-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 2386323 INFO (coreLoadExecutor-4074-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-001/./collection1, trusted=true [junit4] 2> 2386323 INFO (coreLoadExecutor-4074-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17e306f [junit4] 2> 2386323 INFO (coreLoadExecutor-4074-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 2386323 INFO (coreLoadExecutor-4074-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-001/./collection1/data/] [junit4] 2> 2386324 INFO (coreLoadExecutor-4074-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=62.322265625, floorSegmentMB=0.748046875, forceMergeDeletesPctAllowed=8.87096733648037, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 2386350 INFO (coreLoadExecutor-4074-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2386350 INFO (coreLoadExecutor-4074-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2386350 INFO (coreLoadExecutor-4074-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1459734496, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 2386350 INFO (coreLoadExecutor-4074-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@c8ef05[collection1] main] [junit4] 2> 2386351 INFO (coreLoadExecutor-4074-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-001/collection1/conf [junit4] 2> 2386351 INFO (coreLoadExecutor-4074-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl [junit4] 2> 2386351 INFO (coreLoadExecutor-4074-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2386352 INFO (searcherExecutor-4075-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@c8ef05[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2386353 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-002/collection1 [junit4] 2> 2386354 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 2386355 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2af4dd{/solr,null,AVAILABLE} [junit4] 2> 2386355 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@e329fb{HTTP/1.1,[http/1.1]}{127.0.0.1:41947} [junit4] 2> 2386355 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.e.j.s.Server Started @2388238ms [junit4] 2> 2386355 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=41947} [junit4] 2> 2386355 ERROR (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2386355 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.2.0 [junit4] 2> 2386355 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 2386355 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2386355 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-11-10T01:02:51.055Z [junit4] 2> 2386356 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-002/solr.xml [junit4] 2> 2386358 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 2386358 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 2386358 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@17e306f, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 2386382 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17e306f [junit4] 2> 2386390 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17e306f [junit4] 2> 2386390 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17e306f [junit4] 2> 2386392 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-002/. [junit4] 2> 2386392 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 2386392 INFO (coreLoadExecutor-4084-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 2386398 INFO (coreLoadExecutor-4084-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0 [junit4] 2> 2386401 INFO (coreLoadExecutor-4084-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2386402 INFO (coreLoadExecutor-4084-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 2386402 INFO (coreLoadExecutor-4084-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-002/./collection1, trusted=true [junit4] 2> 2386402 INFO (coreLoadExecutor-4084-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17e306f [junit4] 2> 2386402 INFO (coreLoadExecutor-4084-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 2386402 INFO (coreLoadExecutor-4084-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-002/./collection1/data/] [junit4] 2> 2386404 INFO (coreLoadExecutor-4084-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=62.322265625, floorSegmentMB=0.748046875, forceMergeDeletesPctAllowed=8.87096733648037, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 2386430 INFO (coreLoadExecutor-4084-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2386430 INFO (coreLoadExecutor-4084-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2386431 INFO (coreLoadExecutor-4084-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1459734496, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 2386431 INFO (coreLoadExecutor-4084-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@13b80ef[collection1] main] [junit4] 2> 2386431 INFO (coreLoadExecutor-4084-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-002/collection1/conf [junit4] 2> 2386432 INFO (coreLoadExecutor-4084-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 2386432 INFO (coreLoadExecutor-4084-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2386433 INFO (searcherExecutor-4085-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@13b80ef[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2386434 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@e329fb{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 2386435 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=17280386 [junit4] 2> 2386435 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 2386435 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@b94dad: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5ea661 [junit4] 2> 2386440 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 2386440 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1e72b3: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@ebded5 [junit4] 2> 2386444 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 2386444 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1dab3ed: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@53505f [junit4] 2> 2386444 INFO (coreCloseExecutor-4090-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1a2dc1e [junit4] 2> 2386444 INFO (coreCloseExecutor-4090-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=27450398 [junit4] 2> 2386444 INFO (coreCloseExecutor-4090-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@794f65: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1bdd826 [junit4] 2> 2386450 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2af4dd{/solr,null,UNAVAILABLE} [junit4] 2> 2386451 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 2386452 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1514d8a{/solr,null,AVAILABLE} [junit4] 2> 2386452 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2b229a{HTTP/1.1,[http/1.1]}{127.0.0.1:34969} [junit4] 2> 2386452 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.e.j.s.Server Started @2388336ms [junit4] 2> 2386452 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=34969} [junit4] 2> 2386453 ERROR (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2386453 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.2.0 [junit4] 2> 2386453 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 2386453 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2386453 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-11-10T01:02:51.153Z [junit4] 2> 2386453 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-002/solr.xml [junit4] 2> 2386455 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 2386455 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 2386456 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@17e306f, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 2386478 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17e306f [junit4] 2> 2386485 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17e306f [junit4] 2> 2386486 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17e306f [junit4] 2> 2386487 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-002/. [junit4] 2> 2386487 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 2386487 INFO (coreLoadExecutor-4097-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 2386504 INFO (coreLoadExecutor-4097-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0 [junit4] 2> 2386517 INFO (coreLoadExecutor-4097-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2386519 INFO (coreLoadExecutor-4097-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 2386519 INFO (coreLoadExecutor-4097-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-002/./collection1, trusted=true [junit4] 2> 2386519 INFO (coreLoadExecutor-4097-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17e306f [junit4] 2> 2386519 INFO (coreLoadExecutor-4097-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 2386519 INFO (coreLoadExecutor-4097-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-002/./collection1/data/] [junit4] 2> 2386521 INFO (coreLoadExecutor-4097-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=62.322265625, floorSegmentMB=0.748046875, forceMergeDeletesPctAllowed=8.87096733648037, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 2386559 INFO (coreLoadExecutor-4097-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2386559 INFO (coreLoadExecutor-4097-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2386559 INFO (coreLoadExecutor-4097-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1459734496, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 2386559 INFO (coreLoadExecutor-4097-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1cb1bca[collection1] main] [junit4] 2> 2386560 INFO (coreLoadExecutor-4097-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-002/collection1/conf [junit4] 2> 2386560 INFO (coreLoadExecutor-4097-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2386561 INFO (searcherExecutor-4098-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1cb1bca[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2386562 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7dd735{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 2386562 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=31965207 [junit4] 2> 2386562 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 2386562 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@141817d: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@6a826 [junit4] 2> 2386566 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 2386566 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@c71283: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@ebded5 [junit4] 2> 2386568 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 2386568 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@17454e5: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@53505f [junit4] 2> 2386569 INFO (coreCloseExecutor-4102-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@16c6955 [junit4] 2> 2386569 INFO (coreCloseExecutor-4102-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=23882069 [junit4] 2> 2386569 INFO (coreCloseExecutor-4102-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1a6b141: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@e61391 [junit4] 2> 2386573 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@557d2b{/solr,null,UNAVAILABLE} [junit4] 2> 2386574 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 2386575 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1b0e7a2{/solr,null,AVAILABLE} [junit4] 2> 2386575 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6eadbe{HTTP/1.1,[http/1.1]}{127.0.0.1:35025} [junit4] 2> 2386575 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.e.j.s.Server Started @2388458ms [junit4] 2> 2386575 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=35025} [junit4] 2> 2386575 ERROR (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2386575 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.2.0 [junit4] 2> 2386575 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 2386575 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2386575 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-11-10T01:02:51.275Z [junit4] 2> 2386576 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-001/solr.xml [junit4] 2> 2386578 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 2386578 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 2386579 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@17e306f, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 2386602 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17e306f [junit4] 2> 2386609 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17e306f [junit4] 2> 2386609 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17e306f [junit4] 2> 2386611 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-001/. [junit4] 2> 2386611 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 2386611 INFO (coreLoadExecutor-4109-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 2386617 INFO (coreLoadExecutor-4109-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0 [junit4] 2> 2386621 INFO (coreLoadExecutor-4109-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2386622 INFO (coreLoadExecutor-4109-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 2386622 INFO (coreLoadExecutor-4109-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-001/./collection1, trusted=true [junit4] 2> 2386622 INFO (coreLoadExecutor-4109-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17e306f [junit4] 2> 2386622 INFO (coreLoadExecutor-4109-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 2386622 INFO (coreLoadExecutor-4109-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-001/./collection1/data/] [junit4] 2> 2386624 INFO (coreLoadExecutor-4109-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=62.322265625, floorSegmentMB=0.748046875, forceMergeDeletesPctAllowed=8.87096733648037, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 2386647 INFO (coreLoadExecutor-4109-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2386647 INFO (coreLoadExecutor-4109-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2386648 INFO (coreLoadExecutor-4109-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1459734496, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 2386648 INFO (coreLoadExecutor-4109-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ede8b7[collection1] main] [junit4] 2> 2386648 INFO (coreLoadExecutor-4109-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1878EB209465F1E2-001/solr-instance-001/collection1/conf [junit4] 2> 2386649 INFO (coreLoadExecutor-4109-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml [junit4] 2> 2386649 INFO (coreLoadExecutor-4109-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2386650 INFO (searcherExecutor-4110-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1ede8b7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2386651 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 2386651 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0 [junit4] 2> 2386652 INFO (qtp32220505-16296) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 2386652 INFO (qtp32220505-16296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0 [junit4] 2> 2386652 INFO (qtp32220505-16297) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2386652 INFO (qtp32220505-16297) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6bb159 commitCommandVersion:0 [junit4] 2> 2386653 INFO (qtp32220505-16297) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@191401f[collection1] main] [junit4] 2> 2386654 INFO (qtp32220505-16297) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 2386654 INFO (searcherExecutor-4098-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@191401f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2386654 INFO (qtp32220505-16297) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2 [junit4] 2> 2386656 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 0 [junit4] 2> 2386656 INFO (qtp31666261-16318) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0 [junit4] 2> 2386656 INFO (qtp31666261-16311) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0 [junit4] 2> 2386656 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0 [junit4] 2> 2386657 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0 [junit4] 2> 2386657 INFO (qtp31666261-16318) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0 [junit4] 2> 2386657 INFO (qtp31666261-16311) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6]} 0 0 [junit4] 2> 2386657 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7]} 0 0 [junit4] 2> 2386657 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8]} 0 0 [junit4] 2> 2386658 INFO (qtp31666261-16318) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9]} 0 0 [junit4] 2> 2386658 INFO (qtp31666261-16311) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 0 [junit4] 2> 2386658 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11]} 0 0 [junit4] 2> 2386658 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12]} 0 0 [junit4] 2> 2386659 INFO (qtp31666261-16318) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13]} 0 0 [junit4] 2> 2386659 INFO (qtp31666261-16311) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14]} 0 0 [junit4] 2> 2386659 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[15]} 0 0 [junit4] 2> 2386659 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16]} 0 0 [junit4] 2> 2386659 INFO (qtp31666261-16318) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17]} 0 0 [junit4] 2> 2386659 INFO (qtp31666261-16311) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[18]} 0 0 [junit4] 2> 2386660 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19]} 0 0 [junit4] 2> 2386660 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20]} 0 0 [junit4] 2> 2386660 INFO (qtp31666261-16318) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[21]} 0 0 [junit4] 2> 2386660 INFO (qtp31666261-16311) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[22]} 0 0 [junit4] 2> 2386660 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[23]} 0 0 [junit4] 2> 2386660 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[24]} 0 0 [junit4] 2> 2386661 INFO (qtp31666261-16318) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[25]} 0 0 [junit4] 2> 2386661 INFO (qtp31666261-16311) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[26]} 0 0 [junit4] 2> 2386661 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[27]} 0 0 [junit4] 2> 2386661 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[28]} 0 0 [junit4] 2> 2386661 INFO (qtp31666261-16318) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[29]} 0 0 [junit4] 2> 2386661 INFO (qtp31666261-16311) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[30]} 0 0 [junit4] 2> 2386662 INFO (qtp31666261-16311) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[31]} 0 0 [junit4] 2> 2386662 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[32]} 0 0 [junit4] 2> 2386662 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[33]} 0 0 [junit4] 2> 2386662 INFO (qtp31666261-16318) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[34]} 0 0 [junit4] 2> 2386662 INFO (qtp31666261-16311) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[35]} 0 0 [junit4] 2> 2386663 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[36]} 0 0 [junit4] 2> 2386663 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[37]} 0 0 [junit4] 2> 2386663 INFO (qtp31666261-16318) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[38]} 0 0 [junit4] 2> 2386663 INFO (qtp31666261-16311) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[39]} 0 0 [junit4] 2> 2386663 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[40]} 0 0 [junit4] 2> 2386664 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[41]} 0 0 [junit4] 2> 2386664 INFO (qtp31666261-16318) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[42]} 0 0 [junit4] 2> 2386664 INFO (qtp31666261-16311) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[43]} 0 0 [junit4] 2> 2386664 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[44]} 0 0 [junit4] 2> 2386664 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2386664 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3baf7f commitCommandVersion:0 [junit4] 2> 2386667 INFO (qtp31666261-16317) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@bcd9ae[collection1] main] [junit4] 2> 2386667 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 2386668 INFO (searcherExecutor-4110-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@bcd9ae[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):C45)))} [junit4] 2> 2386668 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 3 [junit4] 2> 2386669 INFO (qtp31666261-16318) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=45 status=0 QTime=0 [junit4] 2> 2386669 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 45 docs [junit4] 2> 2386671 INFO (qtp32220505-16291) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={core=collection1&action=status&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2386675 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 2386676 INFO (qtp31666261-16312) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 2386676 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 2386676 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1510275771364 [junit4] 2> 2386676 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 2386676 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's version: 1510275771352 [junit4] 2> 2386676 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 2386678 INFO (qtp31666261-16316) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist} status=0 QTime=1 [junit4] 2> 2386678 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10 [junit4] 2> 2386678 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=true) to MockDirectoryWrapper(RAMDirectory@ef611c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@42e694) [junit4] 2> 2386679 INFO (qtp31666261-16317) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2386679 INFO (qtp31666261-16318) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2386680 INFO (qtp31666261-16312) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2386681 INFO (qtp31666261-16316) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2386681 INFO (qtp31666261-16317) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2386682 INFO (qtp31666261-16318) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2386682 INFO (qtp31666261-16312) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2386683 INFO (qtp31666261-16316) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2386683 INFO (qtp31666261-16317) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2386683 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher File segments_2 did not match. expected checksum is 3811246498 and actual is checksum 3292434679. expected length is 185 and actual length is 117 [junit4] 2> 2386684 INFO (qtp31666261-16318) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2386684 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=true,bytesDownloaded=2671) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@ef611c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@42e694) [junit4] 2> 2386684 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore Updating index properties... index=index.20171110090251378 [junit4] 2> 2386685 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher removing old index directory MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@9f9653 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8187c3; maxCacheMB=0.77734375 maxMergeSizeMB=0.203125)) [junit4] 2> 2386685 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=76.4912109375, floorSegmentMB=0.6064453125, forceMergeDeletesPctAllowed=23.347788740695595, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.42262006270502384 [junit4] 2> 2386685 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 2386685 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@22d2e2[collection1] main] [junit4] 2> 2386686 INFO (searcherExecutor-4098-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@22d2e2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):C45)))} [junit4] 2> 2386686 INFO (qtp32220505-16291) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:35025/solr/collection1/replication&command=fetchindex} status=0 QTime=11 [junit4] 2> 2386688 INFO (qtp32220505-16298) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=45 status=0 QTime=0 [junit4] 2> 2386688 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[1878EB209465F1E2]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 45 docs [junit4] 2> 2386688 INFO (qtp31666261-16311) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 2386689 INFO (qtp31666261-16316) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 2386689 INFO (qtp31666261-16312) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 2386690 INFO (qtp32220505-16292) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=1 [junit4] 2> 2386690 INFO (qtp31666261-16317) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 2386691 INFO (qtp32220505-16293) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 2386691 INFO (qtp32220505-16293) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[45]} 0 0 [junit4] 2> 2386692 INFO (qtp32220505-16293) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[46]} 0 0 [junit4] 2> 2386692 INFO (qtp32220505-16292) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[47]} 0 0 [junit4] 2> 2386692 INFO (qtp32220505-16291) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2386692 INFO (qtp32220505-16291) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@817a32 commitCommandVersion:0 [junit4] 2> 2386693 INFO (qtp32220505-16291) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@e02803[collection1] main] [junit4] 2> 2386693 INFO (qtp32220505-16291) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 2386694 INFO (searcherExecutor-4098-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@e02803[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):C45) Uninverting(_1(7.2.0):C3)))} [junit4] 2> 2386694 INFO (qtp32220505-16291) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1 [junit4] 2> 2386695 INFO (qtp31666261-16311) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[48]} 0 0 [junit4] 2> 2386696 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[49]} 0 0 [junit4] 2> 2386696 INFO (qtp31666261-16312) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[50]} 0 0 [junit4] 2> 2386696 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[51]} 0 0 [junit4] 2> 2386696 INFO (qtp31666261-16311) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[52]} 0 0 [junit4] 2> 2386696 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[53]} 0 0 [junit4] 2> 2386697 INFO (qtp31666261-16312) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[54]} 0 0 [junit4] 2> 2386697 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[55]} 0 0 [junit4] 2> 2386697 INFO (qtp31666261-16311) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[56]} 0 0 [junit4] 2> 2386697 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[57]} 0 0 [junit4] 2> 2386697 INFO (qtp31666261-16312) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[58]} 0 0 [junit4] 2> 2386697 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[59]} 0 0 [junit4] 2> 2386698 INFO (qtp31666261-16311) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[60]} 0 0 [junit4] 2> 2386698 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[61]} 0 0 [junit4] 2> 2386698 INFO (qtp31666261-16312) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[62]} 0 0 [junit4] 2> 2386698 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[63]} 0 0 [junit4] 2> 2386698 INFO (qtp31666261-16311) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[64]} 0 0 [junit4] 2> 2386698 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[65]} 0 0 [junit4] 2> 2386699 INFO (qtp31666261-16312) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[66]} 0 0 [junit4] 2> 2386699 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[67]} 0 0 [junit4] 2> 2386699 INFO (qtp31666261-16311) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[68]} 0 0 [junit4] 2> 2386699 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[69]} 0 0 [junit4] 2> 2386699 INFO (qtp31666261-16312) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[70]} 0 0 [junit4] 2> 2386699 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[71]} 0 0 [junit4] 2> 2386700 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[72]} 0 0 [junit4] 2> 2386700 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[73]} 0 0 [junit4] 2> 2386700 INFO (qtp31666261-16312) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[74]} 0 0 [junit4] 2> 2386700 INFO (qtp31666261-16311) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[75]} 0 0 [junit4] 2> 2386700 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[76]} 0 0 [junit4] 2> 2386700 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[77]} 0 0 [junit4] 2> 2386701 INFO (qtp31666261-16312) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[78]} 0 0 [junit4] 2> 2386701 INFO (qtp31666261-16311) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[79]} 0 0 [junit4] 2> 2386701 INFO (qtp31666261-16317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[80]} 0 0 [junit4] 2> 2386701 INFO (qtp31666261-16316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[81]} 0 0 [junit4] 2> 2386701 INFO (qtp31666261-16312) [ x:collection1] o.a.s.u.p.LogUpdate [...truncated too long message...] FO (coreCloseExecutor-148-thread-7) [n:127.0.0.1:38643_solr c:multicollection2 s:shard1 r:core_node3 x:multicollection2_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1e7b0e1: rootName = solr_38643, domain = solr.core.multicollection2.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.multicollection2.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@1576245 [junit4] 2> 41975 INFO (coreCloseExecutor-149-thread-7) [n:127.0.0.1:39311_solr c:multicollection2 s:shard2 r:core_node4 x:multicollection2_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.multicollection2.shard2.leader, tag=10572753 [junit4] 2> 41976 INFO (coreCloseExecutor-150-thread-1) [n:127.0.0.1:45875_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard1.leader, tag=3231757 [junit4] 2> 41977 INFO (coreCloseExecutor-150-thread-8) [n:127.0.0.1:45875_solr c:multicollection1 s:shard2 r:core_node4 x:multicollection1_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.multicollection1.shard2.replica_n2, tag=5652558 [junit4] 2> 41977 INFO (coreCloseExecutor-150-thread-8) [n:127.0.0.1:45875_solr c:multicollection1 s:shard2 r:core_node4 x:multicollection1_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1478ddc: rootName = solr_45875, domain = solr.core.multicollection1.shard2.replica_n2, service url = null, agent id = null] for registry solr.core.multicollection1.shard2.replica_n2 / com.codahale.metrics.MetricRegistry@4005a1 [junit4] 2> 41985 INFO (coreCloseExecutor-150-thread-7) [n:127.0.0.1:45875_solr c:foo s:shard1 r:core_node5 x:foo_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.foo.shard1.leader, tag=4344577 [junit4] 2> 41985 INFO (coreCloseExecutor-150-thread-2) [n:127.0.0.1:45875_solr c:localShardsTestColl s:shard2 r:core_node13 x:localShardsTestColl_shard2_replica_n9] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.localShardsTestColl.shard2.leader, tag=21635509 [junit4] 2> 41985 INFO (coreCloseExecutor-150-thread-3) [n:127.0.0.1:45875_solr c:localShardsTestColl s:shard3 r:core_node18 x:localShardsTestColl_shard3_replica_n16] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.localShardsTestColl.shard3.leader, tag=3295946 [junit4] 2> 41985 INFO (coreCloseExecutor-150-thread-6) [n:127.0.0.1:45875_solr c:2nd_collection s:shard2 r:core_node4 x:2nd_collection_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.2nd_collection.shard2.leader, tag=314453 [junit4] 2> 41986 INFO (coreCloseExecutor-150-thread-8) [n:127.0.0.1:45875_solr c:multicollection1 s:shard2 r:core_node4 x:multicollection1_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.multicollection1.shard2.leader, tag=5652558 [junit4] 2> 41987 INFO (coreCloseExecutor-148-thread-7) [n:127.0.0.1:38643_solr c:multicollection2 s:shard1 r:core_node3 x:multicollection2_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.multicollection2.shard1.leader, tag=26877364 [junit4] 2> 41987 INFO (coreCloseExecutor-148-thread-1) [n:127.0.0.1:38643_solr c:localShardsTestColl s:shard1 r:core_node3 x:localShardsTestColl_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.localShardsTestColl.shard1.leader, tag=9995012 [junit4] 2> 41987 INFO (coreCloseExecutor-148-thread-3) [n:127.0.0.1:38643_solr c:localShardsTestColl s:shard3 r:core_node15 x:localShardsTestColl_shard3_replica_n11] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.localShardsTestColl.shard3.leader, tag=13617532 [junit4] 2> 41987 INFO (coreCloseExecutor-148-thread-4) [n:127.0.0.1:38643_solr c:2nd_collection s:shard1 r:core_node3 x:2nd_collection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.2nd_collection.shard1.leader, tag=27512977 [junit4] 2> 41987 INFO (coreCloseExecutor-148-thread-5) [n:127.0.0.1:38643_solr c:foo s:shard1 r:core_node3 x:foo_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.foo.shard1.leader, tag=20304667 [junit4] 2> 41987 INFO (coreCloseExecutor-148-thread-6) [n:127.0.0.1:38643_solr c:multicollection1 s:shard1 r:core_node3 x:multicollection1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.multicollection1.shard1.leader, tag=9822811 [junit4] 2> 42014 INFO (jetty-closer-2-thread-3) [ ] o.a.s.c.Overseer Overseer (id=98977504330711048-127.0.0.1:45875_solr-n_0000000000) closing [junit4] 2> 42014 INFO (OverseerStateUpdate-98977504330711048-127.0.0.1:45875_solr-n_0000000000) [n:127.0.0.1:45875_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:45875_solr [junit4] 2> 42014 WARN (OverseerAutoScalingTriggerThread-98977504330711048-127.0.0.1:45875_solr-n_0000000000) [n:127.0.0.1:45875_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 42018 INFO (jetty-closer-2-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@9eb5f3{/solr,null,UNAVAILABLE} [junit4] 2> 42019 INFO (jetty-closer-2-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@175f376{/solr,null,UNAVAILABLE} [junit4] 2> 42019 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 0x15fa3840a630008, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 42020 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 0x15fa3840a630009, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 43521 INFO (jetty-closer-2-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@984122{/solr,null,UNAVAILABLE} [junit4] 2> 43521 ERROR (SUITE-CloudSolrClientTest-seed#[1D670CA03ECF9456]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 43522 INFO (SUITE-CloudSolrClientTest-seed#[1D670CA03ECF9456]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40023 40023 [junit4] 2> 48556 INFO (Thread-1) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40023 40023 [junit4] 2> 48557 WARN (Thread-1) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 8 /solr/aliases.json [junit4] 2> 4 /solr/configs/conf [junit4] 2> 3 /solr/security.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 21 /solr/collections/foo/state.json [junit4] 2> 21 /solr/collections/localShardsTestColl/state.json [junit4] 2> 16 /solr/collections/stale_state_test_col/state.json [junit4] 2> 14 /solr/collections/collection1/state.json [junit4] 2> 12 /solr/collections/2nd_collection/state.json [junit4] 2> 12 /solr/collections/multicollection1/state.json [junit4] 2> 12 /solr/collections/multicollection2/state.json [junit4] 2> 10 /solr/collections/nemesis/state.json [junit4] 2> 8 /solr/clusterprops.json [junit4] 2> 8 /solr/clusterstate.json [junit4] 2> 6 /solr/collections/overwrite/state.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 8 /solr/live_nodes [junit4] 2> 8 /solr/collections [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.CloudSolrClientTest_1D670CA03ECF9456-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {multiDefault=Lucene50(blocksize=128), a_t=FST50, title_s=Lucene50(blocksize=128), id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), text=PostingsFormat(name=Memory)}, docValues:{_version_=DocValuesFormat(name=Lucene70), multiDefault=DocValuesFormat(name=Asserting), title_s=DocValuesFormat(name=Asserting), intDefault=DocValuesFormat(name=Lucene70), id=DocValuesFormat(name=Lucene70), timestamp=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=112, maxMBSortInHeap=5.853434003793147, sim=RandomSimilarity(queryNorm=false): {}, locale=he, timezone=Pacific/Kwajalein [junit4] 2> NOTE: Linux 4.10.0-37-generic i386/Oracle Corporation 1.8.0_144 (32-bit)/cpus=8,threads=1,free=146382192,total=295698432 [junit4] 2> NOTE: All tests run in this JVM: [CloudSolrClientTest] [junit4] Completed [11/146 (1!)] on J2 in 49.41s, 16 tests, 1 error <<< FAILURES! [...truncated 42603 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
