You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/12/10 09:11:27 UTC
[JENKINS] Lucene-Solr-7.x-Solaris (64bit/jdk1.8.0) - Build # 328 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/328/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI
Error Message:
Error from server at http://127.0.0.1:50224/solr/awhollynewcollection_0_shard2_replica_n2: ClusterState says we are the leader (http://127.0.0.1:50224/solr/awhollynewcollection_0_shard2_replica_n2), but locally we don't think so. Request came from null
Stack Trace:
org.apache.solr.client.solrj.impl.CloudSolrClient$RouteException: Error from server at http://127.0.0.1:50224/solr/awhollynewcollection_0_shard2_replica_n2: ClusterState says we are the leader (http://127.0.0.1:50224/solr/awhollynewcollection_0_shard2_replica_n2), but locally we don't think so. Request came from null
at __randomizedtesting.SeedInfo.seed([A262E271DDC36FAA:EA1796C5DBF0403F]:0)
at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:549)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1012)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:883)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:945)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:945)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:945)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:945)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:945)
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.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:459)
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)
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:50224/solr/awhollynewcollection_0_shard2_replica_n2: ClusterState says we are the leader (http://127.0.0.1:50224/solr/awhollynewcollection_0_shard2_replica_n2), but locally we don't think so. Request came from null
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643)
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.lambda$directUpdate$0(CloudSolrClient.java:526)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
... 1 more
Build Log:
[...truncated 12789 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A262E271DDC36FAA-001/init-core-data-001
[junit4] 2> 2608549 WARN (SUITE-CollectionsAPIDistributedZkTest-seed#[A262E271DDC36FAA]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=374 numCloses=374
[junit4] 2> 2608549 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A262E271DDC36FAA]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2608550 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A262E271DDC36FAA]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 2608550 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A262E271DDC36FAA]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2608551 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A262E271DDC36FAA]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A262E271DDC36FAA-001/tempDir-001
[junit4] 2> 2608551 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A262E271DDC36FAA]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2608551 INFO (Thread-7069) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2608551 INFO (Thread-7069) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2608553 ERROR (Thread-7069) [ ] 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> 2608652 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A262E271DDC36FAA]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:42032
[junit4] 2> 2608656 INFO (zkConnectionManagerCallback-4744-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2608665 INFO (jetty-launcher-4741-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 2608665 INFO (jetty-launcher-4741-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 2608665 INFO (jetty-launcher-4741-thread-3) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 2608667 INFO (jetty-launcher-4741-thread-4) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 2608672 INFO (jetty-launcher-4741-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@27591336{/solr,null,AVAILABLE}
[junit4] 2> 2608673 INFO (jetty-launcher-4741-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@16b98dc2{/solr,null,AVAILABLE}
[junit4] 2> 2608674 INFO (jetty-launcher-4741-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@41bd8489{/solr,null,AVAILABLE}
[junit4] 2> 2608674 INFO (jetty-launcher-4741-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3aae2fa1{/solr,null,AVAILABLE}
[junit4] 2> 2608676 INFO (jetty-launcher-4741-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1f699b82{HTTP/1.1,[http/1.1]}{127.0.0.1:50224}
[junit4] 2> 2608676 INFO (jetty-launcher-4741-thread-1) [ ] o.e.j.s.Server Started @2611930ms
[junit4] 2> 2608676 INFO (jetty-launcher-4741-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5afc3e43{HTTP/1.1,[http/1.1]}{127.0.0.1:34089}
[junit4] 2> 2608676 INFO (jetty-launcher-4741-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50224}
[junit4] 2> 2608676 INFO (jetty-launcher-4741-thread-4) [ ] o.e.j.s.Server Started @2611930ms
[junit4] 2> 2608676 INFO (jetty-launcher-4741-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7d559c75{HTTP/1.1,[http/1.1]}{127.0.0.1:42003}
[junit4] 2> 2608676 INFO (jetty-launcher-4741-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34089}
[junit4] 2> 2608676 ERROR (jetty-launcher-4741-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2608676 INFO (jetty-launcher-4741-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6077877{HTTP/1.1,[http/1.1]}{127.0.0.1:42084}
[junit4] 2> 2608676 INFO (jetty-launcher-4741-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 2608676 INFO (jetty-launcher-4741-thread-3) [ ] o.e.j.s.Server Started @2611930ms
[junit4] 2> 2608677 INFO (jetty-launcher-4741-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2608677 INFO (jetty-launcher-4741-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42003}
[junit4] 2> 2608677 INFO (jetty-launcher-4741-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2608677 INFO (jetty-launcher-4741-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-10T08:22:12.413Z
[junit4] 2> 2608677 ERROR (jetty-launcher-4741-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2608677 INFO (jetty-launcher-4741-thread-2) [ ] o.e.j.s.Server Started @2611931ms
[junit4] 2> 2608677 INFO (jetty-launcher-4741-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 2608677 INFO (jetty-launcher-4741-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42084}
[junit4] 2> 2608677 INFO (jetty-launcher-4741-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2608677 INFO (jetty-launcher-4741-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2608678 ERROR (jetty-launcher-4741-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2608676 ERROR (jetty-launcher-4741-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2608678 INFO (jetty-launcher-4741-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 2608678 INFO (jetty-launcher-4741-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 2608678 INFO (jetty-launcher-4741-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2608678 INFO (jetty-launcher-4741-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2608678 INFO (jetty-launcher-4741-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-10T08:22:12.414Z
[junit4] 2> 2608678 INFO (jetty-launcher-4741-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-10T08:22:12.414Z
[junit4] 2> 2608678 INFO (jetty-launcher-4741-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2608680 INFO (jetty-launcher-4741-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2608680 INFO (jetty-launcher-4741-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-10T08:22:12.416Z
[junit4] 2> 2608682 INFO (zkConnectionManagerCallback-4746-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2608682 INFO (zkConnectionManagerCallback-4750-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2608683 INFO (zkConnectionManagerCallback-4748-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2608684 INFO (jetty-launcher-4741-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2608684 INFO (jetty-launcher-4741-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2608684 INFO (zkConnectionManagerCallback-4752-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2608685 INFO (jetty-launcher-4741-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2608687 INFO (jetty-launcher-4741-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2608691 INFO (jetty-launcher-4741-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2608691 INFO (jetty-launcher-4741-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2608691 INFO (jetty-launcher-4741-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2608691 INFO (jetty-launcher-4741-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2608691 INFO (jetty-launcher-4741-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2608691 INFO (jetty-launcher-4741-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2608707 INFO (jetty-launcher-4741-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2608707 INFO (jetty-launcher-4741-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2608708 INFO (jetty-launcher-4741-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42032/solr
[junit4] 2> 2608708 INFO (jetty-launcher-4741-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42032/solr
[junit4] 2> 2608710 INFO (zkConnectionManagerCallback-4760-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2608712 INFO (jetty-launcher-4741-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42032/solr
[junit4] 2> 2608712 INFO (jetty-launcher-4741-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42032/solr
[junit4] 2> 2608712 INFO (zkConnectionManagerCallback-4763-thread-1-processing-n:127.0.0.1:42003_solr) [n:127.0.0.1:42003_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2608713 INFO (zkConnectionManagerCallback-4758-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2608716 INFO (zkConnectionManagerCallback-4770-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2608716 INFO (zkConnectionManagerCallback-4768-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2608718 INFO (zkConnectionManagerCallback-4772-thread-1-processing-n:127.0.0.1:42084_solr) [n:127.0.0.1:42084_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2608720 INFO (zkConnectionManagerCallback-4774-thread-1-processing-n:127.0.0.1:34089_solr) [n:127.0.0.1:34089_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2608721 INFO (zkConnectionManagerCallback-4776-thread-1-processing-n:127.0.0.1:50224_solr) [n:127.0.0.1:50224_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2608863 INFO (jetty-launcher-4741-thread-4) [n:127.0.0.1:34089_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2608864 INFO (jetty-launcher-4741-thread-2) [n:127.0.0.1:42084_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2608864 INFO (jetty-launcher-4741-thread-4) [n:127.0.0.1:34089_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:34089_solr
[junit4] 2> 2608866 INFO (jetty-launcher-4741-thread-4) [n:127.0.0.1:34089_solr ] o.a.s.c.Overseer Overseer (id=99149029853626379-127.0.0.1:34089_solr-n_0000000000) starting
[junit4] 2> 2608866 INFO (jetty-launcher-4741-thread-2) [n:127.0.0.1:42084_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42084_solr
[junit4] 2> 2608866 INFO (jetty-launcher-4741-thread-1) [n:127.0.0.1:50224_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2608868 INFO (zkCallback-4771-thread-1-processing-n:127.0.0.1:42084_solr) [n:127.0.0.1:42084_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2608868 INFO (jetty-launcher-4741-thread-3) [n:127.0.0.1:42003_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2608870 INFO (zkCallback-4775-thread-1-processing-n:127.0.0.1:50224_solr) [n:127.0.0.1:50224_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2608871 INFO (zkCallback-4773-thread-1-processing-n:127.0.0.1:34089_solr) [n:127.0.0.1:34089_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2608872 INFO (jetty-launcher-4741-thread-1) [n:127.0.0.1:50224_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50224_solr
[junit4] 2> 2608872 INFO (jetty-launcher-4741-thread-3) [n:127.0.0.1:42003_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2608873 INFO (zkCallback-4771-thread-1-processing-n:127.0.0.1:42084_solr) [n:127.0.0.1:42084_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2608874 INFO (zkCallback-4762-thread-1-processing-n:127.0.0.1:42003_solr) [n:127.0.0.1:42003_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2608875 INFO (jetty-launcher-4741-thread-3) [n:127.0.0.1:42003_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42003_solr
[junit4] 2> 2608881 INFO (zkCallback-4771-thread-1-processing-n:127.0.0.1:42084_solr) [n:127.0.0.1:42084_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2608881 INFO (zkCallback-4762-thread-1-processing-n:127.0.0.1:42003_solr) [n:127.0.0.1:42003_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2608886 INFO (zkCallback-4775-thread-1-processing-n:127.0.0.1:50224_solr) [n:127.0.0.1:50224_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
[junit4] 2> 2608891 INFO (zkCallback-4773-thread-1-processing-n:127.0.0.1:34089_solr) [n:127.0.0.1:34089_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
[junit4] 2> 2608915 INFO (jetty-launcher-4741-thread-4) [n:127.0.0.1:34089_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34089_solr
[junit4] 2> 2608920 INFO (zkCallback-4771-thread-1-processing-n:127.0.0.1:42084_solr) [n:127.0.0.1:42084_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2608920 INFO (zkCallback-4762-thread-1-processing-n:127.0.0.1:42003_solr) [n:127.0.0.1:42003_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2608921 INFO (zkCallback-4775-thread-1-processing-n:127.0.0.1:50224_solr) [n:127.0.0.1:50224_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2608921 INFO (zkCallback-4773-thread-1-processing-n:127.0.0.1:34089_solr) [n:127.0.0.1:34089_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2608956 INFO (jetty-launcher-4741-thread-2) [n:127.0.0.1:42084_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b4e1796
[junit4] 2> 2608961 INFO (jetty-launcher-4741-thread-2) [n:127.0.0.1:42084_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b4e1796
[junit4] 2> 2608961 INFO (jetty-launcher-4741-thread-2) [n:127.0.0.1:42084_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b4e1796
[junit4] 2> 2608962 INFO (jetty-launcher-4741-thread-2) [n:127.0.0.1:42084_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A262E271DDC36FAA-001/tempDir-001/node2
[junit4] 2> 2608998 INFO (jetty-launcher-4741-thread-3) [n:127.0.0.1:42003_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b4e1796
[junit4] 2> 2609005 INFO (jetty-launcher-4741-thread-3) [n:127.0.0.1:42003_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b4e1796
[junit4] 2> 2609005 INFO (jetty-launcher-4741-thread-3) [n:127.0.0.1:42003_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b4e1796
[junit4] 2> 2609006 INFO (jetty-launcher-4741-thread-3) [n:127.0.0.1:42003_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A262E271DDC36FAA-001/tempDir-001/node3
[junit4] 2> 2609047 INFO (jetty-launcher-4741-thread-4) [n:127.0.0.1:34089_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b4e1796
[junit4] 2> 2609047 INFO (jetty-launcher-4741-thread-1) [n:127.0.0.1:50224_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b4e1796
[junit4] 2> 2609053 WARN (jetty-launcher-4741-thread-1) [n:127.0.0.1:50224_solr ] o.a.s.m.r.j.JmxMetricsReporter Unable to register gauge
[junit4] 2> javax.management.InstanceNotFoundException: solr:dom1=jvm,name0=system,name=properties
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1095)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:427)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit4] 2> at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546)
[junit4] 2> at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter$JmxListener.registerMBean(JmxMetricsReporter.java:530)
[junit4] 2> at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter$JmxListener.onGaugeAdded(JmxMetricsReporter.java:563)
[junit4] 2> at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter.lambda$start$0(JmxMetricsReporter.java:738)
[junit4] 2> at java.util.HashMap.forEach(HashMap.java:1289)
[junit4] 2> at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter.start(JmxMetricsReporter.java:728)
[junit4] 2> at org.apache.solr.metrics.reporters.SolrJmxReporter.doInit(SolrJmxReporter.java:109)
[junit4] 2> at org.apache.solr.metrics.SolrMetricReporter.init(SolrMetricReporter.java:70)
[junit4] 2> at org.apache.solr.metrics.SolrMetricManager.loadReporter(SolrMetricManager.java:881)
[junit4] 2> at org.apache.solr.metrics.SolrMetricManager.loadReporters(SolrMetricManager.java:817)
[junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:549)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.createCoreContainer(SolrDispatchFilter.java:263)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:183)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.initialize(FilterHolder.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:873)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.updateMappings(ServletHandler.java:1596)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.setFilterMappings(ServletHandler.java:1659)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterMapping(ServletHandler.java:1316)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:1145)
[junit4] 2> at org.eclipse.jetty.servlet.ServletContextHandler.addFilter(ServletContextHandler.java:448)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$1.lifeCycleStarted(JettySolrRunner.java:306)
[junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.setStarted(AbstractLifeCycle.java:179)
[junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:69)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:394)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:367)
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.startJettySolrRunner(MiniSolrCloudCluster.java:384)
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.lambda$new$0(MiniSolrCloudCluster.java:247)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 2609054 INFO (jetty-launcher-4741-thread-4) [n:127.0.0.1:34089_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b4e1796
[junit4] 2> 2609054 INFO (jetty-launcher-4741-thread-4) [n:127.0.0.1:34089_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b4e1796
[junit4] 2> 2609054 INFO (jetty-launcher-4741-thread-1) [n:127.0.0.1:50224_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b4e1796
[junit4] 2> 2609054 INFO (jetty-launcher-4741-thread-1) [n:127.0.0.1:50224_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b4e1796
[junit4] 2> 2609055 INFO (jetty-launcher-4741-thread-4) [n:127.0.0.1:34089_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A262E271DDC36FAA-001/tempDir-001/node4
[junit4] 2> 2609055 INFO (jetty-launcher-4741-thread-1) [n:127.0.0.1:50224_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A262E271DDC36FAA-001/tempDir-001/node1
[junit4] 2> 2609095 INFO (zkConnectionManagerCallback-4783-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2609098 INFO (zkConnectionManagerCallback-4787-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2609101 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A262E271DDC36FAA]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2609102 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A262E271DDC36FAA]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42032/solr ready
[junit4] 2> 2609133 INFO (TEST-CollectionsAPIDistributedZkTest.testCreationAndDeletion-seed#[A262E271DDC36FAA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreationAndDeletion
[junit4] 2> 2609134 INFO (TEST-CollectionsAPIDistributedZkTest.testCreationAndDeletion-seed#[A262E271DDC36FAA]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2609137 INFO (qtp1687981056-23864) [n:127.0.0.1:42003_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=created_and_deleted&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2609141 INFO (OverseerThreadFactory-8199-thread-1-processing-n:127.0.0.1:34089_solr) [n:127.0.0.1:34089_solr ] o.a.s.c.CreateCollectionCmd Create collection created_and_deleted
[junit4] 2> 2609258 INFO (OverseerStateUpdate-99149029853626379-127.0.0.1:34089_solr-n_0000000000) [n:127.0.0.1:34089_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"created_and_deleted",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"created_and_deleted_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:34089/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2609469 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=created_and_deleted_shard1_replica_n1&action=CREATE&numShards=1&collection=created_and_deleted&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2609469 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2609580 INFO (zkCallback-4773-thread-1-processing-n:127.0.0.1:34089_solr) [n:127.0.0.1:34089_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/created_and_deleted/state.json] for collection [created_and_deleted] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2609580 INFO (zkCallback-4773-thread-2-processing-n:127.0.0.1:34089_solr) [n:127.0.0.1:34089_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/created_and_deleted/state.json] for collection [created_and_deleted] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2610500 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 2610513 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.s.IndexSchema [created_and_deleted_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2610518 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2610518 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'created_and_deleted_shard1_replica_n1' using configuration from collection created_and_deleted, trusted=true
[junit4] 2> 2610519 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.created_and_deleted.shard1.replica_n1' (registry 'solr.core.created_and_deleted.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b4e1796
[junit4] 2> 2610519 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2610519 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.c.SolrCore [[created_and_deleted_shard1_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A262E271DDC36FAA-001/tempDir-001/node4/created_and_deleted_shard1_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A262E271DDC36FAA-001/tempDir-001/node4/created_and_deleted_shard1_replica_n1/data/]
[junit4] 2> 2610598 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2610598 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2610599 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2610599 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2610600 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5aee39e8[created_and_deleted_shard1_replica_n1] main]
[junit4] 2> 2610602 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 2610602 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2610603 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2610603 INFO (searcherExecutor-8208-thread-1-processing-n:127.0.0.1:34089_solr x:created_and_deleted_shard1_replica_n1 s:shard1 c:created_and_deleted r:core_node2) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.c.SolrCore [created_and_deleted_shard1_replica_n1] Registered new searcher Searcher@5aee39e8[created_and_deleted_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2610603 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1586384479808651264
[junit4] 2> 2610609 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2610609 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2610609 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:34089/solr/created_and_deleted_shard1_replica_n1/
[junit4] 2> 2610609 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2610609 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:34089/solr/created_and_deleted_shard1_replica_n1/ has no replicas
[junit4] 2> 2610609 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 2610613 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:34089/solr/created_and_deleted_shard1_replica_n1/ shard1
[junit4] 2> 2610717 INFO (zkCallback-4773-thread-2-processing-n:127.0.0.1:34089_solr) [n:127.0.0.1:34089_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/created_and_deleted/state.json] for collection [created_and_deleted] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2610717 INFO (zkCallback-4773-thread-1-processing-n:127.0.0.1:34089_solr) [n:127.0.0.1:34089_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/created_and_deleted/state.json] for collection [created_and_deleted] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2610766 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2610776 INFO (qtp1178988193-23870) [n:127.0.0.1:34089_solr c:created_and_deleted s:shard1 r:core_node2 x:created_and_deleted_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=created_and_deleted_shard1_replica_n1&action=CREATE&numShards=1&collection=created_and_deleted&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1309
[junit4] 2> 2610786 INFO (qtp1687981056-23864) [n:127.0.0.1:42003_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 2610879 INFO (zkCallback-4773-thread-2-processing-n:127.0.0.1:34089_solr) [n:127.0.0.1:34089_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/created_and_deleted/state.json] for collection [created_and_deleted] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2610879 INFO (zkCallback-4773-thread-1-processing-n:127.0.0.1:34089_solr) [n:127.0.0.1:34089_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/created_and_deleted/state.json] for collection [created_and_deleted] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2611146 INFO (OverseerCollectionConfigSetProcessor-99149029853626379-127.0.0.1:34089_solr-n_0000000000) [n:127.0.0.1:34089_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2611788 INFO (qtp1687981056-23864) [n:127.0.0.1:42003_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=created_and_deleted&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=2651
[junit4] 2> 2611788 INFO (qtp1687981056-23866) [n:127.0.0.1:42003_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2611789 INFO (qtp1687981056-23866) [n:127.0.0.1:42003_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2611789 INFO (qtp1687981056-23867) [n:127.0.0.1:42003_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=created_and_deleted&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2611792 INFO (OverseerThreadFactory-8199-thread-2-processing-n:127.0.0.1:34089_solr) [n:127.0.0.1:34089_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 2611793 INFO (qtp1178988193-23871) [n:127.0.0.1:34089_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.created_and_deleted.shard1.replica_n1, tag=null
[junit4] 2> 2611793 INFO (qtp1178988193-23871) [n:127.0.0.1:34089_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6dd3d68d: rootName = null, domain = solr.core.created_and_deleted.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.created_and_deleted.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@69d5d6bc
[junit4] 2> 2611800 INFO (qtp1178988193-23871) [n:127.0.0.1:34089_solr ] o.a.s.c.SolrCore [created_and_deleted_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@5cf5ab4
[junit4] 2> 2611800 INFO (qtp1178988193-23871) [n:127.0.0.1:34089_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.created_and_deleted.shard1.replica_n1, tag=97475252
[junit4] 2> 2611800 INFO (qtp1178988193-23871) [n:127.0.0.1:34089_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.created_and_deleted.shard1.leader, tag=97475252
[junit4] 2> 2611802 INFO (qtp1178988193-23871) [n:127.0.0.1:34089_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=created_and_deleted_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=10
[junit4] 2> 2612517 INFO (qtp1687981056-23867) [n:127.0.0.1:42003_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=created_and_deleted&action=DELETE&wt=javabin&version=2} status=0 QTime=727
[junit4] 2> 2612518 INFO (qtp1687981056-23868) [n:127.0.0.1:42003_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2612519 INFO (qtp1687981056-23868) [n:127.0.0.1:42003_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2612520 INFO (TEST-CollectionsAPIDistributedZkTest.testCreationAndDeletion-seed#[A262E271DDC36FAA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCreationAndDeletion
[junit4] 2> 2612585 INFO (TEST-CollectionsAPIDistributedZkTest.testCollectionsAPI-seed#[A262E271DDC36FAA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCollectionsAPI
[junit4] 2> 2612589 INFO (TEST-CollectionsAPIDistributedZkTest.testCollectionsAPI-seed#[A262E271DDC36FAA]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2612593 INFO (qtp1687981056-23877) [n:127.0.0.1:42003_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params async=c0155433-7828-48c3-8bfc-4ca9267dc1f4&replicationFactor=1&collection.configName=conf2&maxShardsPerNode=1&name=awhollynewcollection_0&nrtReplicas=1&action=CREATE&numShards=3&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2612598 INFO (qtp1687981056-23877) [n:127.0.0.1:42003_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=c0155433-7828-48c3-8bfc-4ca9267dc1f4&replicationFactor=1&collection.configName=conf2&maxShardsPerNode=1&name=awhollynewcollection_0&nrtReplicas=1&action=CREATE&numShards=3&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 2612604 INFO (OverseerThreadFactory-8199-thread-3-processing-n:127.0.0.1:34089_solr) [n:127.0.0.1:34089_solr ] o.a.s.c.CreateCollectionCmd Create collection awhollynewcollection_0
[junit4] 2> 2612605 INFO (OverseerCollectionConfigSetProcessor-99149029853626379-127.0.0.1:34089_solr-n_0000000000) [n:127.0.0.1:34089_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2612725 INFO (zkCallback-4786-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2612841 INFO (OverseerStateUpdate-99149029853626379-127.0.0.1:34089_solr-n_0000000000) [n:127.0.0.1:34089_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"awhollynewcollection_0",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"awhollynewcollection_0_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:42084/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2612848 INFO (OverseerStateUpdate-99149029853626379-127.0.0.1:34089_solr-n_0000000000) [n:127.0.0.1:34089_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"awhollynewcollection_0",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"awhollynewcollection_0_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:50224/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2612855 INFO (OverseerStateUpdate-99149029853626379-127.0.0.1:34089_solr-n_0000000000) [n:127.0.0.1:34089_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"awhollynewcollection_0",
[junit4] 2> "shard":"shard3",
[junit4] 2> "core":"awhollynewcollection_0_shard3_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:42003/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2612960 INFO (zkCallback-4786-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2613059 INFO (qtp1634331810-23852) [n:127.0.0.1:42084_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173&coreNodeName=core_node3&name=awhollynewcollection_0_shard1_replica_n1&action=CREATE&numShards=3&shard=shard1&wt=javabin} status=0 QTime=0
[junit4] 2> 2613061 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173&coreNodeName=core_node3&name=awhollynewcollection_0_shard1_replica_n1&action=CREATE&numShards=3&shard=shard1&wt=javabin
[junit4] 2> 2613061 INFO (qtp296686683-23845) [n:127.0.0.1:50224_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428&coreNodeName=core_node5&name=awhollynewcollection_0_shard2_replica_n2&action=CREATE&numShards=3&shard=shard2&wt=javabin} status=0 QTime=0
[junit4] 2> 2613061 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2613062 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428&coreNodeName=core_node5&name=awhollynewcollection_0_shard2_replica_n2&action=CREATE&numShards=3&shard=shard2&wt=javabin
[junit4] 2> 2613063 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2613063 INFO (qtp1687981056-23878) [n:127.0.0.1:42003_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099&coreNodeName=core_node6&name=awhollynewcollection_0_shard3_replica_n4&action=CREATE&numShards=3&shard=shard3&wt=javabin} status=0 QTime=1
[junit4] 2> 2613064 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099&coreNodeName=core_node6&name=awhollynewcollection_0_shard3_replica_n4&action=CREATE&numShards=3&shard=shard3&wt=javabin
[junit4] 2> 2613065 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2613068 INFO (qtp1634331810-23852) [n:127.0.0.1:42084_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173
[junit4] 2> 2613068 INFO (qtp1634331810-23852) [n:127.0.0.1:42084_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2613183 INFO (zkCallback-4775-thread-1-processing-n:127.0.0.1:50224_solr) [n:127.0.0.1:50224_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2613183 INFO (zkCallback-4775-thread-2-processing-n:127.0.0.1:50224_solr) [n:127.0.0.1:50224_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2613183 INFO (zkCallback-4771-thread-2-processing-n:127.0.0.1:42084_solr) [n:127.0.0.1:42084_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2613183 INFO (zkCallback-4771-thread-1-processing-n:127.0.0.1:42084_solr) [n:127.0.0.1:42084_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2613183 INFO (zkCallback-4786-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2613184 INFO (zkCallback-4762-thread-1-processing-n:127.0.0.1:42003_solr) [n:127.0.0.1:42003_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2613184 INFO (zkCallback-4762-thread-2-processing-n:127.0.0.1:42003_solr) [n:127.0.0.1:42003_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2614077 INFO (qtp1634331810-23857) [n:127.0.0.1:42084_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173
[junit4] 2> 2614077 INFO (qtp1634331810-23857) [n:127.0.0.1:42084_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2614099 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 2614099 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 2614099 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 2614119 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.s.IndexSchema [awhollynewcollection_0_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2614119 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.s.IndexSchema [awhollynewcollection_0_shard3_replica_n4] Schema name=minimal
[junit4] 2> 2614119 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.s.IndexSchema [awhollynewcollection_0_shard2_replica_n2] Schema name=minimal
[junit4] 2> 2614121 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2614121 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'awhollynewcollection_0_shard1_replica_n1' using configuration from collection awhollynewcollection_0, trusted=true
[junit4] 2> 2614121 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2614121 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.CoreContainer Creating SolrCore 'awhollynewcollection_0_shard3_replica_n4' using configuration from collection awhollynewcollection_0, trusted=true
[junit4] 2> 2614121 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2614121 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'awhollynewcollection_0_shard2_replica_n2' using configuration from collection awhollynewcollection_0, trusted=true
[junit4] 2> 2614121 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.awhollynewcollection_0.shard3.replica_n4' (registry 'solr.core.awhollynewcollection_0.shard3.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b4e1796
[junit4] 2> 2614121 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.awhollynewcollection_0.shard1.replica_n1' (registry 'solr.core.awhollynewcollection_0.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b4e1796
[junit4] 2> 2614122 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2614122 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.awhollynewcollection_0.shard2.replica_n2' (registry 'solr.core.awhollynewcollection_0.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b4e1796
[junit4] 2> 2614122 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.SolrCore [[awhollynewcollection_0_shard3_replica_n4] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A262E271DDC36FAA-001/tempDir-001/node3/awhollynewcollection_0_shard3_replica_n4], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A262E271DDC36FAA-001/tempDir-001/node3/awhollynewcollection_0_shard3_replica_n4/data/]
[junit4] 2> 2614122 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2614122 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.c.SolrCore [[awhollynewcollection_0_shard2_replica_n2] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A262E271DDC36FAA-001/tempDir-001/node1/awhollynewcollection_0_shard2_replica_n2], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A262E271DDC36FAA-001/tempDir-001/node1/awhollynewcollection_0_shard2_replica_n2/data/]
[junit4] 2> 2614122 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2614122 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.SolrCore [[awhollynewcollection_0_shard1_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A262E271DDC36FAA-001/tempDir-001/node2/awhollynewcollection_0_shard1_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A262E271DDC36FAA-001/tempDir-001/node2/awhollynewcollection_0_shard1_replica_n1/data/]
[junit4] 2> 2614174 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2614174 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2614175 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2614175 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2614175 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2614175 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2614175 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2614175 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2614176 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2614176 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2614176 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2614176 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2614176 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@1375982f[awhollynewcollection_0_shard2_replica_n2] main]
[junit4] 2> 2614177 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1e22a5e1[awhollynewcollection_0_shard1_replica_n1] main]
[junit4] 2> 2614177 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Searcher@1fcea449[awhollynewcollection_0_shard3_replica_n4] main]
[junit4] 2> 2614178 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 2614178 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 2614178 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 2614178 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 2614178 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 2614178 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 2614179 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2614179 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2614179 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2614179 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1586384483558359040
[junit4] 2> 2614179 INFO (searcherExecutor-8213-thread-1-processing-x:awhollynewcollection_0_shard1_replica_n1 r:core_node3 c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE n:127.0.0.1:42084_solr s:shard1 c:awhollynewcollection_0) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.SolrCore [awhollynewcollection_0_shard1_replica_n1] Registered new searcher Searcher@1e22a5e1[awhollynewcollection_0_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2614180 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1586384483558359040
[junit4] 2> 2614180 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1586384483559407616
[junit4] 2> 2614180 INFO (searcherExecutor-8215-thread-1-processing-x:awhollynewcollection_0_shard2_replica_n2 r:core_node5 c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE n:127.0.0.1:50224_solr s:shard2 c:awhollynewcollection_0) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.c.SolrCore [awhollynewcollection_0_shard2_replica_n2] Registered new searcher Searcher@1375982f[awhollynewcollection_0_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2614182 INFO (searcherExecutor-8214-thread-1-processing-x:awhollynewcollection_0_shard3_replica_n4 r:core_node6 c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE n:127.0.0.1:42003_solr s:shard3 c:awhollynewcollection_0) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.SolrCore [awhollynewcollection_0_shard3_replica_n4] Registered new searcher Searcher@1fcea449[awhollynewcollection_0_shard3_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2614185 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2614185 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2614186 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:50224/solr/awhollynewcollection_0_shard2_replica_n2/
[junit4] 2> 2614186 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2614186 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2614186 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:42003/solr/awhollynewcollection_0_shard3_replica_n4/
[junit4] 2> 2614186 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2614186 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2614186 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.SyncStrategy http://127.0.0.1:42003/solr/awhollynewcollection_0_shard3_replica_n4/ has no replicas
[junit4] 2> 2614186 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 2614186 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.c.SyncStrategy http://127.0.0.1:50224/solr/awhollynewcollection_0_shard2_replica_n2/ has no replicas
[junit4] 2> 2614187 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 2614188 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2614188 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2614188 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:42084/solr/awhollynewcollection_0_shard1_replica_n1/
[junit4] 2> 2614188 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2614188 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:42084/solr/awhollynewcollection_0_shard1_replica_n1/ has no replicas
[junit4] 2> 2614189 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 2614192 INFO (parallelCoreAdminExecutor-8202-thread-1-processing-n:127.0.0.1:42003_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683007756099 CREATE) [n:127.0.0.1:42003_solr c:awhollynewcollection_0 s:shard3 r:core_node6 x:awhollynewcollection_0_shard3_replica_n4] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:42003/solr/awhollynewcollection_0_shard3_replica_n4/ shard3
[junit4] 2> 2614193 INFO (parallelCoreAdminExecutor-8204-thread-1-processing-n:127.0.0.1:50224_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636683001295428 CREATE) [n:127.0.0.1:50224_solr c:awhollynewcollection_0 s:shard2 r:core_node5 x:awhollynewcollection_0_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:50224/solr/awhollynewcollection_0_shard2_replica_n2/ shard2
[junit4] 2> 2614195 INFO (parallelCoreAdminExecutor-8200-thread-1-processing-n:127.0.0.1:42084_solr c0155433-7828-48c3-8bfc-4ca9267dc1f4636682993679173 CREATE) [n:127.0.0.1:42084_solr c:awhollynewcollection_0 s:shard1 r:core_node3 x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:42084/solr/awhollynewcollection_0_shard1_replica_n1/ shard1
[junit4] 2> 2614301 INFO (zkCallback-4775-thread-2-processing-n:127.0.0.1:50224_solr) [n:127.0.0.1:50224_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2614301 INFO (zkCallback-4786-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2614302 INFO (zkCallback-4771-thread-2-processing-n:127.0.0.1:42084_solr) [n:127.0.0.1:42084_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2614302 INFO (zkCallback-4775-thread-1-processing-n:127.0.0.1:50224_solr) [n:127.0.0.1:50224_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 2614302 INFO (zkCallback-4762-thread-2-processing-n:1
[...truncated too long message...]
O (OverseerStateUpdate-99149029853626379-127.0.0.1:34089_solr-n_0000000000) [n:127.0.0.1:34089_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:34089_solr
[junit4] 2> 2689395 INFO (zkCallback-4773-thread-1-processing-n:127.0.0.1:34089_solr) [n:127.0.0.1:34089_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2689397 WARN (OverseerAutoScalingTriggerThread-99149029853626379-127.0.0.1:34089_solr-n_0000000000) [n:127.0.0.1:34089_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 2689402 INFO (jetty-closer-4742-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@16b98dc2{/solr,null,UNAVAILABLE}
[junit4] 2> 2689404 INFO (zkCallback-4797-thread-5-processing-n:127.0.0.1:50224_solr) [n:127.0.0.1:50224_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50224_solr
[junit4] 2> 2689406 INFO (jetty-closer-4742-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2689407 INFO (jetty-closer-4742-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@44359030: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@7ca6a6d0
[junit4] 2> 2689409 INFO (jetty-closer-4742-thread-4) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:50224_solr
[junit4] 2> 2689411 INFO (jetty-closer-4742-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2689412 INFO (zkCallback-4797-thread-5-processing-n:127.0.0.1:50224_solr) [n:127.0.0.1:50224_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 2689414 INFO (jetty-closer-4742-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@9f36aac{/solr,null,UNAVAILABLE}
[junit4] 2> 2690902 INFO (jetty-closer-4742-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3aae2fa1{/solr,null,UNAVAILABLE}
[junit4] 2> 2690906 ERROR (SUITE-CollectionsAPIDistributedZkTest-seed#[A262E271DDC36FAA]-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> 2690907 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A262E271DDC36FAA]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42032 42032
[junit4] 2> 2692037 INFO (Thread-7069) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42032 42032
[junit4] 2> 2692039 WARN (Thread-7069) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 25 /solr/aliases.json
[junit4] 2> 25 /solr/clusterprops.json
[junit4] 2> 22 /solr/configs/conf
[junit4] 2> 5 /solr/security.json
[junit4] 2> 5 /solr/configs/conf2
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 48 /solr/collections/addReplicaColl/state.json
[junit4] 2> 32 /solr/collections/nodes_used_collection/state.json
[junit4] 2> 25 /solr/clusterstate.json
[junit4] 2> 24 /solr/collections/reloaded_collection/state.json
[junit4] 2> 22 /solr/collections/awhollynewcollection_0/state.json
[junit4] 2> 15 /solr/collections/acollectionafterbaddelete/state.json
[junit4] 2> 13 /solr/collections/halfdeletedcollection/state.json
[junit4] 2> 10 /solr/collections/onlyinzk/state.json
[junit4] 2> 5 /solr/collections/created_and_deleted/state.json
[junit4] 2> 5 /solr/collections/withconfigset2/state.json
[junit4] 2> 4 /solr/collections/halfcollectionblocker/state.json
[junit4] 2> 4 /solr/collections/halfcollection/state.json
[junit4] 2> 4 /solr/collections/halfcollectionblocker2/state.json
[junit4] 2> 3 /solr/collections/addReplicaColl/leader_elect/shard1/election/99149029853626379-core_node5-n_0000000000
[junit4] 2> 2 /solr/overseer_elect/election/99149029853626379-127.0.0.1:34089_solr-n_0000000000
[junit4] 2> 2 /solr/overseer_elect/election/99149029853626378-127.0.0.1:42084_solr-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 338 /solr/collections
[junit4] 2> 29 /solr/live_nodes
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A262E271DDC36FAA-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {id=PostingsFormat(name=MockRandom)}, docValues:{_version_=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=1461, maxMBSortInHeap=7.206478789038578, sim=RandomSimilarity(queryNorm=false): {}, locale=bg, timezone=Australia/NSW
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=3,threads=1,free=172807680,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [MoveReplicaHDFSFailoverTest, XsltUpdateRequestHandlerTest, ClassificationUpdateProcessorFactoryTest, AsyncCallRequestStatusResponseTest, TestUtils, TestExactStatsCache, SuggesterTSTTest, ParsingFieldUpdateProcessorsTest, OverseerModifyCollectionTest, TestFiltering, TestCustomStream, PeerSyncWithIndexFingerprintCachingTest, DistributedSpellCheckComponentTest, DistributedTermsComponentTest, TestInPlaceUpdatesStandalone, UnloadDistributedZkTest, MigrateRouteKeyTest, SolrCmdDistributorTest, DefaultValueUpdateProcessorTest, SolrCLIZkUtilsTest, TestSha256AuthenticationProvider, MetricsHandlerTest, BlockCacheTest, TestHdfsUpdateLog, TestReqParamsAPI, TestIndexingPerformance, TestCoreAdminApis, TestSmileRequest, TestReplicationHandler, TestFilteredDocIdSet, TlogReplayBufferedWhileIndexingTest, TestMissingGroups, TestSolr4Spatial, RequestHandlersTest, SliceStateTest, TestAddFieldRealTimeGet, DistributedVersionInfoTest, TestScoreJoinQPScore, AutoAddReplicasIntegrationTest, TestSubQueryTransformerDistrib, TestCSVResponseWriter, TestFieldCollectionResource, ManagedSchemaRoundRobinCloudTest, TestTolerantUpdateProcessorRandomCloud, MinimalSchemaTest, TestQuerySenderListener, DirectUpdateHandlerOptimizeTest, VersionInfoTest, TestExpandComponent, SolrRequestParserTest, BinaryUpdateRequestHandlerTest, CoreAdminCreateDiscoverTest, TestSchemaSimilarityResource, AnalyticsQueryTest, TestLocalFSCloudBackupRestore, ReplicationFactorTest, CloudMLTQParserTest, TestSolrCoreProperties, AddReplicaTest, RollingRestartTest, TestDistributedSearch, DirectUpdateHandlerTest, DeleteInactiveReplicaTest, TestRangeQuery, IndexSchemaTest, HdfsLockFactoryTest, HighlighterTest, ClassificationUpdateProcessorTest, TestLFUCache, ExplicitHLLTest, DistributedFacetPivotLargeTest, TestSchemaNameResource, SharedFSAutoReplicaFailoverTest, CdcrRequestHandlerTest, RecoveryZkTest, ZkCLITest, TestRawTransformer, TestGraphMLResponseWriter, TestZkAclsWithHadoopAuth, TestIBSimilarityFactory, OutputWriterTest, ConfigSetsAPITest, TestExportWriter, CdcrBidirectionalTest, TestCloudDeleteByQuery, TestDownShardTolerantSearch, SystemLogListenerTest, TestSolrCloudWithSecureImpersonation, TestBackupRepositoryFactory, ClusterStateUpdateTest, TestCloudSearcherWarming, TestNestedDocsSort, TestHdfsBackupRestoreCore, TestSerializedLuceneMatchVersion, DistribJoinFromCollectionTest, SparseHLLTest, TestXIncludeConfig, SolrInfoBeanTest, TestTlogReplica, GraphQueryTest, TestReloadAndDeleteDocs, XmlUpdateRequestHandlerTest, ReturnFieldsTest, MoveReplicaTest, TestSearcherReuse, NumericFieldsTest, DistributedFacetPivotSmallAdvancedTest, ZkControllerTest, TestReversedWildcardFilterFactory, AtomicUpdateProcessorFactoryTest, RequiredFieldsTest, DistanceUnitsTest, TestUseDocValuesAsStored2, SpellCheckCollatorWithCollapseTest, TestLegacyNumericRangeQueryBuilder, TestJmxIntegration, SolrGangliaReporterTest, StatsComponentTest, ChaosMonkeyNothingIsSafeTest, UniqFieldsUpdateProcessorFactoryTest, RulesTest, TestStressInPlaceUpdates, CleanupOldIndexTest, RegexBoostProcessorTest, TestConfigSetImmutable, BJQParserTest, TestFieldCacheVsDocValues, TestSubQueryTransformer, TestSolrCloudWithDelegationTokens, TestConfigSets, CoreMergeIndexesAdminHandlerTest, SpatialHeatmapFacetsTest, CSVRequestHandlerTest, TestPseudoReturnFields, ZkStateReaderTest, LeaderElectionContextKeyTest, DistribCursorPagingTest, TestFieldTypeCollectionResource, TestXmlQParserPlugin, TestOmitPositions, TestIntervalFaceting, FacetPivotSmallTest, PathHierarchyTokenizerFactoryTest, SignatureUpdateProcessorFactoryTest, NodeLostTriggerTest, TestSystemCollAutoCreate, DirectoryFactoryTest, TestPushWriter, TestRequestStatusCollectionAPI, PeerSyncReplicationTest, SuggesterWFSTTest, TestSolrDeletionPolicy2, AutoScalingHandlerTest, SortByFunctionTest, TestLeaderElectionZkExpiry, TestLegacyFieldCache, ResponseLogComponentTest, CheckHdfsIndexTest, TestDynamicFieldCollectionResource, TestNoOpRegenerator, TestDistribIDF, BasicAuthIntegrationTest, TestHighlightDedupGrouping, DistanceFunctionTest, TestBlendedInfixSuggestions, TestDeleteCollectionOnDownNodes, CircularListTest, TestEmbeddedSolrServerSchemaAPI, SyncSliceTest, LeaderInitiatedRecoveryOnShardRestartTest, TestComplexPhraseLeadingWildcard, TestHashQParserPlugin, CurrencyFieldTypeTest, OverriddenZkACLAndCredentialsProvidersTest, TestCollectionAPI, BufferStoreTest, TestConfigSetsAPIZkFailure, TestRecovery, TestWriterPerf, SolrShardReporterTest, JavabinLoaderTest, BasicDistributedZkTest, CollectionsAPIDistributedZkTest]
[junit4] Completed [379/751 (1!)] on J0 in 83.51s, 19 tests, 1 error <<< FAILURES!
[...truncated 48597 lines...]
[JENKINS] Lucene-Solr-7.x-Solaris (64bit/jdk1.8.0) - Build # 329 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/329/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
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([73497D3052C371BB:6701266571C4CCA5]:0)
at java.util.ArrayList.rangeCheck(ArrayList.java:657)
at java.util.ArrayList.get(ArrayList.java:433)
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 12789 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/init-core-data-001
[junit4] 2> 1992963 WARN (SUITE-TestReplicationHandler-seed#[73497D3052C371BB]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=123 numCloses=123
[junit4] 2> 1992963 INFO (SUITE-TestReplicationHandler-seed#[73497D3052C371BB]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1992964 INFO (SUITE-TestReplicationHandler-seed#[73497D3052C371BB]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 1992964 INFO (SUITE-TestReplicationHandler-seed#[73497D3052C371BB]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1992971 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestIllegalFilePaths
[junit4] 2> 1992971 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-001/collection1
[junit4] 2> 1992975 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1992975 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@27d17b0f{/solr,null,AVAILABLE}
[junit4] 2> 1992978 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@373deb1d{HTTP/1.1,[http/1.1]}{127.0.0.1:35040}
[junit4] 2> 1992978 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.e.j.s.Server Started @1996371ms
[junit4] 2> 1992978 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=35040}
[junit4] 2> 1992978 ERROR (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1992979 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0
[junit4] 2> 1992979 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1992979 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1992979 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-10T20:07:36.356Z
[junit4] 2> 1992979 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-001/solr.xml
[junit4] 2> 1992982 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1992982 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1992983 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@4a8c81d3, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1993016 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a8c81d3
[junit4] 2> 1993022 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a8c81d3
[junit4] 2> 1993022 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a8c81d3
[junit4] 2> 1993024 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-001/.
[junit4] 2> 1993024 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1993024 INFO (coreLoadExecutor-7191-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1993034 INFO (coreLoadExecutor-7191-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 1993040 INFO (coreLoadExecutor-7191-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1993042 INFO (coreLoadExecutor-7191-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1993042 INFO (coreLoadExecutor-7191-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-001/./collection1, trusted=true
[junit4] 2> 1993042 INFO (coreLoadExecutor-7191-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@4a8c81d3
[junit4] 2> 1993042 INFO (coreLoadExecutor-7191-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1993042 INFO (coreLoadExecutor-7191-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-001/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-001/./collection1/data/]
[junit4] 2> 1993044 INFO (coreLoadExecutor-7191-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1617844243, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1993087 INFO (coreLoadExecutor-7191-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1993087 INFO (coreLoadExecutor-7191-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1993087 INFO (coreLoadExecutor-7191-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=81.0927734375, floorSegmentMB=1.4677734375, forceMergeDeletesPctAllowed=27.600146225144105, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1993087 INFO (coreLoadExecutor-7191-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@12813059[collection1] main]
[junit4] 2> 1993088 INFO (coreLoadExecutor-7191-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-001/collection1/conf
[junit4] 2> 1993088 INFO (coreLoadExecutor-7191-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1993088 INFO (coreLoadExecutor-7191-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1993089 INFO (searcherExecutor-7192-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@12813059[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1993089 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-002/collection1
[junit4] 2> 1993092 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1993093 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7d41e72d{/solr,null,AVAILABLE}
[junit4] 2> 1993093 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6f7a5cde{HTTP/1.1,[http/1.1]}{127.0.0.1:42037}
[junit4] 2> 1993093 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.e.j.s.Server Started @1996486ms
[junit4] 2> 1993093 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=42037}
[junit4] 2> 1993093 ERROR (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1993095 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0
[junit4] 2> 1993096 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1993096 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1993096 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-10T20:07:36.473Z
[junit4] 2> 1993096 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-002/solr.xml
[junit4] 2> 1993098 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1993098 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1993099 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@4a8c81d3, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1993155 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a8c81d3
[junit4] 2> 1993162 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a8c81d3
[junit4] 2> 1993162 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a8c81d3
[junit4] 2> 1993165 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-002/.
[junit4] 2> 1993165 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1993166 INFO (coreLoadExecutor-7201-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1993173 INFO (coreLoadExecutor-7201-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 1993179 INFO (coreLoadExecutor-7201-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1993181 INFO (coreLoadExecutor-7201-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1993181 INFO (coreLoadExecutor-7201-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-002/./collection1, trusted=true
[junit4] 2> 1993182 INFO (coreLoadExecutor-7201-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@4a8c81d3
[junit4] 2> 1993182 INFO (coreLoadExecutor-7201-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1993182 INFO (coreLoadExecutor-7201-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-002/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-002/./collection1/data/]
[junit4] 2> 1993187 INFO (coreLoadExecutor-7201-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1617844243, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1993222 INFO (coreLoadExecutor-7201-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1993222 INFO (coreLoadExecutor-7201-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1993223 INFO (coreLoadExecutor-7201-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=81.0927734375, floorSegmentMB=1.4677734375, forceMergeDeletesPctAllowed=27.600146225144105, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1993224 INFO (coreLoadExecutor-7201-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@231faca8[collection1] main]
[junit4] 2> 1993224 INFO (coreLoadExecutor-7201-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-002/collection1/conf
[junit4] 2> 1993226 INFO (coreLoadExecutor-7201-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 1993226 INFO (coreLoadExecutor-7201-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1993226 INFO (searcherExecutor-7202-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@231faca8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1993234 ERROR (qtp2010866944-17358) [ x:collection1] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: File name must be relative
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$DirectoryFileStream.validateFilenameOrError(ReplicationHandler.java:1496)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$DirectoryFileStream.<init>(ReplicationHandler.java:1469)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.getFileStream(ReplicationHandler.java:593)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:276)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2503)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:710)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:516)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:493)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1993235 INFO (qtp2010866944-17358) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={file=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/foo&command=filecontent} status=403 QTime=0
[junit4] 2> 1993236 ERROR (qtp2010866944-17362) [ x:collection1] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: File name cannot contain ..
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$DirectoryFileStream.lambda$validateFilenameOrError$0(ReplicationHandler.java:1492)
[junit4] 2> at java.lang.Iterable.forEach(Iterable.java:75)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$DirectoryFileStream.validateFilenameOrError(ReplicationHandler.java:1490)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$DirectoryFileStream.<init>(ReplicationHandler.java:1469)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.getFileStream(ReplicationHandler.java:593)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:276)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2503)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:710)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:516)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:493)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1993236 INFO (qtp2010866944-17362) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={file=../dir/traversal&command=filecontent} status=403 QTime=0
[junit4] 2> 1993246 ERROR (qtp2010866944-17363) [ x:collection1] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: File name must be relative
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$DirectoryFileStream.validateFilenameOrError(ReplicationHandler.java:1496)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$DirectoryFileStream.<init>(ReplicationHandler.java:1470)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$LocalFsFileStream.<init>(ReplicationHandler.java:1615)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$LocalFsConfFileStream.<init>(ReplicationHandler.java:1681)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.getFileStream(ReplicationHandler.java:589)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:276)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2503)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:710)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:516)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:493)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1993246 INFO (qtp2010866944-17363) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={cf=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/foo&command=filecontent} status=403 QTime=8
[junit4] 2> 1993247 ERROR (qtp2010866944-17357) [ x:collection1] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: File name cannot contain ..
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$DirectoryFileStream.lambda$validateFilenameOrError$0(ReplicationHandler.java:1492)
[junit4] 2> at java.lang.Iterable.forEach(Iterable.java:75)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$DirectoryFileStream.validateFilenameOrError(ReplicationHandler.java:1490)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$DirectoryFileStream.<init>(ReplicationHandler.java:1470)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$LocalFsFileStream.<init>(ReplicationHandler.java:1615)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$LocalFsConfFileStream.<init>(ReplicationHandler.java:1681)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.getFileStream(ReplicationHandler.java:589)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:276)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2503)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:710)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:516)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:493)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1993248 INFO (qtp2010866944-17357) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={cf=../dir/traversal&command=filecontent} status=403 QTime=0
[junit4] 2> 1993251 ERROR (qtp2010866944-17358) [ x:collection1] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: File name must be relative
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$DirectoryFileStream.validateFilenameOrError(ReplicationHandler.java:1496)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$DirectoryFileStream.<init>(ReplicationHandler.java:1471)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$LocalFsFileStream.<init>(ReplicationHandler.java:1615)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$LocalFsTlogFileStream.<init>(ReplicationHandler.java:1668)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.getFileStream(ReplicationHandler.java:591)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:276)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2503)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:710)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:516)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:493)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1993251 INFO (qtp2010866944-17358) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={tlogFile=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/foo&command=filecontent} status=403 QTime=1
[junit4] 2> 1993252 ERROR (qtp2010866944-17360) [ x:collection1] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: File name cannot contain ..
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$DirectoryFileStream.lambda$validateFilenameOrError$0(ReplicationHandler.java:1492)
[junit4] 2> at java.lang.Iterable.forEach(Iterable.java:75)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$DirectoryFileStream.validateFilenameOrError(ReplicationHandler.java:1490)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$DirectoryFileStream.<init>(ReplicationHandler.java:1471)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$LocalFsFileStream.<init>(ReplicationHandler.java:1615)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$LocalFsTlogFileStream.<init>(ReplicationHandler.java:1668)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.getFileStream(ReplicationHandler.java:591)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:276)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2503)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:710)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:516)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:493)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1993252 INFO (qtp2010866944-17360) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={tlogFile=../dir/traversal&command=filecontent} status=403 QTime=0
[junit4] 2> 1993253 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestIllegalFilePaths
[junit4] 2> 1993254 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@373deb1d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1993254 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1032125137
[junit4] 2> 1993254 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1993254 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@127460af: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@17e4d569
[junit4] 2> 1993259 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1993259 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1f84cb95: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@376ab052
[junit4] 2> 1993263 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1993263 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@22177fde: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@26c7fc0d
[junit4] 2> 1993263 INFO (coreCloseExecutor-7207-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@40926e9b
[junit4] 2> 1993263 INFO (coreCloseExecutor-7207-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1083338395
[junit4] 2> 1993263 INFO (coreCloseExecutor-7207-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@b6834d7: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@3c9fdbf9
[junit4] 2> 1993268 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@27d17b0f{/solr,null,UNAVAILABLE}
[junit4] 2> 1993270 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6f7a5cde{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1993270 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=339945079
[junit4] 2> 1993270 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1993270 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@68d30dc3: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@97c8b73
[junit4] 2> 1993274 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1993274 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6c454814: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@376ab052
[junit4] 2> 1993277 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1993277 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@46ea6b8f: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@26c7fc0d
[junit4] 2> 1993278 INFO (coreCloseExecutor-7209-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4fca846b
[junit4] 2> 1993278 INFO (coreCloseExecutor-7209-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1338672235
[junit4] 2> 1993278 INFO (coreCloseExecutor-7209-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4cf86cf4: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@6cd79759
[junit4] 2> 1993283 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[73497D3052C371BB]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7d41e72d{/solr,null,UNAVAILABLE}
[junit4] 2> 1993292 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestIndexAndConfigReplication
[junit4] 2> 1993292 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-003/collection1
[junit4] 2> 1993295 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1993295 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2e7a1636{/solr,null,AVAILABLE}
[junit4] 2> 1993296 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@8641b18{HTTP/1.1,[http/1.1]}{127.0.0.1:59278}
[junit4] 2> 1993296 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.e.j.s.Server Started @1996689ms
[junit4] 2> 1993296 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-003/collection1/data, hostContext=/solr, hostPort=59278}
[junit4] 2> 1993296 ERROR (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1993296 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0
[junit4] 2> 1993296 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1993296 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1993296 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-10T20:07:36.673Z
[junit4] 2> 1993297 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-003/solr.xml
[junit4] 2> 1993303 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1993303 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1993304 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@4a8c81d3, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1993336 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a8c81d3
[junit4] 2> 1993343 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a8c81d3
[junit4] 2> 1993343 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a8c81d3
[junit4] 2> 1993344 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-003/.
[junit4] 2> 1993345 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1993345 INFO (coreLoadExecutor-7216-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1993356 INFO (coreLoadExecutor-7216-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 1993362 INFO (coreLoadExecutor-7216-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1993364 INFO (coreLoadExecutor-7216-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1993364 INFO (coreLoadExecutor-7216-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-003/./collection1, trusted=true
[junit4] 2> 1993364 INFO (coreLoadExecutor-7216-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@4a8c81d3
[junit4] 2> 1993364 INFO (coreLoadExecutor-7216-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1993364 INFO (coreLoadExecutor-7216-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-003/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-003/./collection1/data/]
[junit4] 2> 1993365 INFO (coreLoadExecutor-7216-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1617844243, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1993403 INFO (coreLoadExecutor-7216-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1993404 INFO (coreLoadExecutor-7216-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1993404 INFO (coreLoadExecutor-7216-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=81.0927734375, floorSegmentMB=1.4677734375, forceMergeDeletesPctAllowed=27.600146225144105, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1993404 INFO (coreLoadExecutor-7216-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5950b4a4[collection1] main]
[junit4] 2> 1993404 INFO (coreLoadExecutor-7216-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-003/collection1/conf
[junit4] 2> 1993404 INFO (coreLoadExecutor-7216-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1993404 INFO (coreLoadExecutor-7216-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1993404 INFO (searcherExecutor-7217-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5950b4a4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1993408 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-004/collection1
[junit4] 2> 1993412 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1993413 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@54de8638{/solr,null,AVAILABLE}
[junit4] 2> 1993413 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2925a2ae{HTTP/1.1,[http/1.1]}{127.0.0.1:42134}
[junit4] 2> 1993413 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.e.j.s.Server Started @1996807ms
[junit4] 2> 1993414 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-004/collection1/data, hostContext=/solr, hostPort=42134}
[junit4] 2> 1993414 ERROR (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1993414 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0
[junit4] 2> 1993414 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1993414 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1993416 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-10T20:07:36.793Z
[junit4] 2> 1993416 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-004/solr.xml
[junit4] 2> 1993419 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1993419 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1993419 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@4a8c81d3, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1993453 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a8c81d3
[junit4] 2> 1993464 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a8c81d3
[junit4] 2> 1993464 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a8c81d3
[junit4] 2> 1993465 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-004/.
[junit4] 2> 1993465 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[73497D3052C371BB]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1993466 INFO (coreLoadExecutor-7226-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1993475 INFO (coreLoadExecutor-7226-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 1993520 INFO (coreLoadExecutor-7226-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1993522 INFO (coreLoadExecutor-7226-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1993522 INFO (coreLoadExecutor-7226-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-004/./collection1, trusted=true
[junit4] 2> 1993522 INFO (coreLoadExecutor-7226-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@4a8c81d3
[junit4] 2> 1993522 INFO (coreLoadExecutor-7226-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1993522 INFO (coreLoadExecutor-7226-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-004/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-004/./collection1/data/]
[junit4] 2> 1993526 INFO (coreLoadExecutor-7226-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1617844243, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1993567 INFO (coreLoadExecutor-7226-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1993567 INFO (coreLoadExecutor-7226-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1993568 INFO (coreLoadExecutor-7226-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=81.0927734375, floorSegmentMB=1.4677734375, forceMergeDeletesPctAllowed=27.600146225144105, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1993568 INFO (coreLoadExecutor-7226-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@221fbbef[collection1] main]
[junit4] 2> 1993568 INFO (coreLoadExecutor-7226-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001/solr-instance-004/collection1/conf
[junit4] 2> 1993569 INFO (coreLoadExecutor-7226-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 1993569 INFO (coreLoadExecutor-7226-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1993577 INFO (qtp1557481209-17399) [ 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> 1993578 INFO (indexFetcher-7231-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 1993578 INFO (indexFetcher-7231-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 1993578 INFO (qtp1557481209-17401) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 1993578 INFO (indexFetcher-7231-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1993578 INFO (indexFetcher-7231-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's version: 0
[junit4] 2> 1993578 INFO (indexFetcher-7231-thread-1) [ x:collection1] o.a.s.h.IndexFetcher New index in Master. Deleting mine...
[junit4] 2> 1993578 INFO (indexFetcher-7231-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1993578 INFO (indexFetcher-7231-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3cf4d82 commitCommandVersion:0
[junit4] 2> 1993578 INFO (searcherExecutor-7227-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@221fbbef[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1993579 INFO (indexFetcher-7231-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@a20ef9e[collection1] main]
[junit4] 2> 1993579 INFO (qtp1557481209-17404) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 0
[junit4] 2> 1993579 INFO (indexFetcher-7231-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1993579 INFO (qtp1557481209-17403) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 1993580 INFO (searcherExecutor-7227-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@a20ef9e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1993580 INFO (qtp1557481209-17405) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 1993581 INFO (qtp1557481209-17398) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 1993581 INFO (qtp1557481209-17399) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 1993581 INFO (qtp1557481209-17402) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 1993582 INFO (qtp1557481209-17401) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6]} 0 0
[junit4] 2> 1993582 INFO (qtp1557481209-17404) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7]} 0 0
[junit4] 2> 1993583 INFO (qtp1557481209-17403) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8]} 0 0
[junit4] 2> 1993583 INFO (qtp1557481209-17405) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9]} 0 0
[junit4] 2> 1993584 INFO (qtp1557481209-17398) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 0
[junit4] 2> 1993584 INFO (qtp1557481209-17399) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11]} 0 0
[junit4] 2> 1993584 INFO (qtp1557481209-17402) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12]} 0 0
[junit4] 2> 1993589 INFO (qtp1557481209-17402) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13]} 0 0
[junit4] 2> 1993589 INFO (qtp1557481209-17404) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14]} 0 0
[junit4] 2> 1993589 INFO (qtp1557481209-17403) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[15]} 0 0
[junit4] 2> 1993590 INFO (qtp1557481209-17405) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16]} 0 0
[junit4] 2> 1993590 INFO (qtp1557481209-17398) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17]} 0 0
[junit4] 2> 1993591 INFO (qtp1557481209-17399) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[18]} 0 0
[junit4] 2> 1993591 INFO (qtp1557481209-17401) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19]} 0 0
[junit4] 2> 1993591 INFO (qtp1557481209-17402) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20]} 0 0
[junit4] 2> 1993592 INFO (qtp1557481209-17404) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[21]} 0 0
[junit4] 2> 1993592 INFO (qtp1557481209-17403) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[22]} 0 0
[junit4] 2> 1993593 INFO (qtp1557481209-17405) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[23]} 0 0
[junit4] 2> 1993593 INFO (qtp1557481209-17398) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[24]} 0 0
[junit4] 2> 1993593 INFO (qtp1557481209-17399) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[25]} 0 0
[junit4] 2> 1993594 INFO (qtp1557481209-17401) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[26]} 0 0
[junit4] 2> 1993594 INFO (qtp1557481209-17402) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[27]} 0 0
[junit4] 2> 1993594 INFO (qtp1557481209-17404) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[28]} 0 0
[junit4] 2> 1993595 INFO (qtp1557481209-17403) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[29]} 0 0
[junit4] 2> 1993595 INFO (qtp1557481209-17405) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[30]} 0 0
[junit4] 2> 1993596 INFO (qtp1557481209-17398) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[31]} 0 0
[junit4] 2> 1993596 INFO (qtp1557481209-17399) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[32]} 0 0
[junit4] 2> 1993596 INFO (qtp1557481209-17401) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[33]} 0 0
[junit4] 2> 1993597 INFO (qtp1557481209-17402) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[34]} 0 0
[junit4] 2> 1993597 INFO (qtp1557481209-17404) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[35]} 0 0
[junit4] 2> 1993597 INFO (qtp1557481209-17403) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[36]} 0 0
[junit4] 2> 1993598 INFO (qtp1557481209-17405) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[37]} 0 0
[junit4] 2> 1993598 INFO (qtp1557481209-17398) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[38]} 0 0
[junit4] 2> 1993599 INFO (qtp1557481209-17399) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[39]} 0 0
[junit4] 2> 1993599 INFO (qtp1557481209-17399) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[40]} 0 0
[junit4] 2> 1993600 INFO (qtp1557481209-17399) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[41]} 0 0
[junit4] 2> 1993600 INFO (qtp1557481209-17404) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[42]} 0 0
[junit4] 2> 1993601 INFO (qtp1557481209-17403) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[43]} 0 0
[junit4] 2> 1993601 INFO (qtp1557481209-17405) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[44]} 0 0
[junit4] 2> 1993601 INFO (qtp1557481209-17398) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[45]} 0 0
[junit4] 2> 1993602 INFO (qtp1557481209-17401) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[46]} 0 0
[junit4] 2> 1993602 INFO (qtp1557481209-17402) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[47]} 0 0
[junit4] 2> 1993602 INFO (qtp1557481209-17399) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[48]} 0 0
[junit4] 2> 1993603 INFO (qtp1557481209-17404) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[49]} 0 0
[junit4] 2> 1993603 INFO (qtp1557481209-17403) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[50]} 0 0
[junit4] 2> 1993604 INFO (qtp1557481209-17405) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[51]} 0 0
[junit4] 2> 1993604 INFO (qtp1557481209-17398) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[52]} 0 0
[junit4] 2> 1993604 INFO (qtp1557481209-17401) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[53]} 0 0
[junit4] 2> 1993605 INFO (qtp1557481209-17402) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[54]} 0 0
[junit4] 2> 1993605 INFO (qtp1557481209-17399) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[55]} 0 0
[junit4] 2> 1993606 INFO (qtp1557481209-17404) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[56]} 0 0
[junit4] 2> 1993606 INFO (qtp1557481209-17403) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[57]} 0 0
[junit4] 2> 1993606 INFO (qtp1557481209-17405) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[58]} 0 0
[junit4] 2> 1993607 INFO (qtp1557481209-17398) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[59]} 0 0
[junit4] 2> 1993607 INFO (qtp1557481209-17401) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[60]} 0 0
[junit4] 2> 1993608 INFO (qtp1557481209-17402) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[61]} 0 0
[junit4] 2> 1993608 INFO (qtp1557481209-17399) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[62]} 0 0
[junit4] 2> 1993608 INFO (qtp1557481209-17404) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[63]} 0 0
[junit4] 2> 1993609 INFO (qtp1557481209-17403) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[64]} 0 0
[junit4] 2> 1993609 INFO (qtp1557481209-17403) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[65]} 0 0
[junit4] 2> 1993609 INFO (qtp1557481209-17398) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[66]} 0 0
[junit4] 2> 1993610 INFO (qtp1557481209-17401) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[67]} 0 0
[junit4] 2> 1993610 INFO (qtp1557481209-17402) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[68]} 0 0
[junit4] 2> 1993611 INFO (qtp1557481209-17399) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[69]} 0 0
[junit4] 2> 1993611 INFO (qtp1557481209-17404) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[70]} 0 0
[junit4] 2> 1993611 INFO (qtp1557481209-17405) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[71]} 0 0
[junit4] 2> 1993612 INFO (qtp1557481209-17403) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[72]} 0 0
[junit4] 2> 1993612 INFO (qtp1557481209-17398) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[73]} 0 0
[junit4] 2> 1993613 INFO (qtp1557481209-17401) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[74]} 0 0
[junit4] 2> 1993613 INFO (qtp1557481209-17402) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[75]} 0 0
[junit4] 2> 1993613 INFO (qtp1557481209-17399) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[76]} 0 0
[junit4] 2> 1993614 INFO (qtp1557481209-17404) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/updat
[...truncated too long message...]
dler.testEmptyCommits-seed#[73497D3052C371BB]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2065976 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@55712511: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@26c7fc0d
[junit4] 2> 2065976 INFO (coreCloseExecutor-7865-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@74dfc2f8
[junit4] 2> 2065976 INFO (coreCloseExecutor-7865-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1960821496
[junit4] 2> 2065976 INFO (coreCloseExecutor-7865-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@66667e2d: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2c6da06b
[junit4] 2> 2065982 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[73497D3052C371BB]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@707704ab{/solr,null,UNAVAILABLE}
[junit4] 2> 2065985 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[73497D3052C371BB]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@754b55f6{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 2065985 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[73497D3052C371BB]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=951873428
[junit4] 2> 2065985 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[73497D3052C371BB]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2065985 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@35510a19: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@4ebadc56
[junit4] 2> 2065990 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[73497D3052C371BB]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2065990 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6b654f3a: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@376ab052
[junit4] 2> 2065993 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[73497D3052C371BB]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2065993 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[73497D3052C371BB]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7bcdbba9: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@26c7fc0d
[junit4] 2> 2065994 INFO (coreCloseExecutor-7867-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@53662764
[junit4] 2> 2065994 INFO (coreCloseExecutor-7867-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1399203684
[junit4] 2> 2065994 INFO (coreCloseExecutor-7867-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@29859494: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@16113070
[junit4] 2> 2066003 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[73497D3052C371BB]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@400c47d6{/solr,null,UNAVAILABLE}
[junit4] 2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_73497D3052C371BB-001
[junit4] 2> NOTE: test params are: codec=Lucene70, sim=RandomSimilarity(queryNorm=false): {}, locale=sr-Latn-BA, timezone=Europe/Gibraltar
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=3,threads=1,free=352274744,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [CacheHeaderTest, TestEmbeddedSolrServerConstructors, TestJavabinTupleStreamParser, TestUninvertingReader, TestDFISimilarityFactory, MergeStrategyTest, DistributedFacetExistsSmallTest, PluginInfoTest, TestStressLucene, AnalysisErrorHandlingTest, TestSchemaResource, PolyFieldTest, SOLR749Test, TestRuleBasedAuthorizationPlugin, TestDistributedGrouping, PreAnalyzedUpdateProcessorTest, TestSubQueryTransformerCrossCore, SimpleMLTQParserTest, TestManagedSchemaThreadSafety, ComputePlanActionTest, PreAnalyzedFieldTest, TestApiFramework, TestSolrConfigHandler, TestRandomCollapseQParserPlugin, TestSolrCloudSnapshots, TestSolrDeletionPolicy1, LeaderFailoverAfterPartitionTest, TestCorePropertiesReload, TestInPlaceUpdatesDistrib, TestSkipOverseerOperations, TestComplexPhraseQParserPlugin, TestComponentsName, HdfsWriteToMultipleCollectionsTest, BlockJoinFacetSimpleTest, TestObjectReleaseTracker, DistributedQueueTest, TestDistributedStatsComponentCardinality, TestCustomDocTransformer, TestSolr4Spatial2, TestFileDictionaryLookup, TestOnReconnectListenerSupport, NumberUtilsTest, ClassificationUpdateProcessorIntegrationTest, LoggingHandlerTest, TestRTGBase, TestAnalyzeInfixSuggestions, TestPartialUpdateDeduplication, TestExclusionRuleCollectionAccess, TestXmlQParser, ConnectionManagerTest, AssignTest, TestCSVLoader, OpenExchangeRatesOrgProviderTest, TestFieldCacheWithThreads, DeleteLastCustomShardedReplicaTest, MBeansHandlerTest, SchemaVersionSpecificBehaviorTest, TestCrossCoreJoin, TestDynamicFieldResource, OutOfBoxZkACLAndCredentialsProvidersTest, LegacyCloudClusterPropTest, ExternalFileFieldSortTest, TestUniqueKeyFieldResource, ActionThrottleTest, AliasIntegrationTest, CloneFieldUpdateProcessorFactoryTest, TestFoldingMultitermQuery, IgnoreCommitOptimizeUpdateProcessorFactoryTest, LeaderInitiatedRecoveryOnCommitTest, OverseerRolesTest, QueryResultKeyTest, DistributedMLTComponentTest, TestChildDocTransformer, FieldMutatingUpdateProcessorTest, TestSimpleTrackingShardHandler, ShardRoutingCustomTest, TestCharFilters, TestFaceting, CachingDirectoryFactoryTest, TestFieldSortValues, TestManagedSchema, TestTrackingShardHandlerFactory, SimpleFacetsTest, TestLegacyField, TestManagedSynonymFilterFactory, PropertiesRequestHandlerTest, TestFastWriter, SimplePostToolTest, DistributedQueryComponentCustomSortTest, DocumentBuilderTest, RegexBytesRefFilterTest, MultiThreadedOCPTest, SolrCloudReportersTest, RequestLoggingTest, TestScoreJoinQPNoScore, AtomicUpdatesTest, DistributedIntervalFacetingTest, BasicZkTest, ChangedSchemaMergeTest, TestStreamBody, TestLMJelinekMercerSimilarityFactory, BasicAuthStandaloneTest, HttpPartitionTest, TestNumericTerms32, PingRequestHandlerTest, TestStressCloudBlindAtomicUpdates, SegmentsInfoRequestHandlerTest, BaseCdcrDistributedZkTest, CoreAdminHandlerTest, UtilsToolTest, TestDelegationWithHadoopAuth, TestRandomFlRTGCloud, CoreAdminRequestStatusTest, TestManagedResource, TestGroupingSearch, TestRawResponseWriter, TestLuceneIndexBackCompat, TestCloudRecovery, TestRequestForwarding, TestCollationFieldDocValues, BadComponentTest, TestSolrQueryParser, OverseerCollectionConfigSetProcessorTest, DistributedSuggestComponentTest, TestFieldTypeResource, SoftAutoCommitTest, TestReloadDeadlock, TestMultiWordSynonyms, TestReRankQParserPlugin, BigEndianAscendingWordDeserializerTest, TestFieldCacheSortRandom, SystemInfoHandlerTest, SolrCloudExampleTest, HighlighterMaxOffsetTest, HdfsDirectoryTest, TestOverriddenPrefixQueryForCustomFieldType, TestFieldResource, TestBadConfig, QueryEqualityTest, DistribDocExpirationUpdateProcessorTest, TestPerFieldSimilarityWithDefaultOverride, InfixSuggestersTest, BasicFunctionalityTest, DistributedFacetPivotSmallTest, TestCollapseQParserPlugin, EchoParamsTest, DeleteReplicaTest, BlockJoinFacetDistribTest, TestNumericRangeQuery64, TermVectorComponentTest, TestCollationField, HLLUtilTest, MetricUtilsTest, AutoAddReplicasPlanActionTest, NodeAddedTriggerTest, TestDocumentBuilder, CloudExitableDirectoryReaderTest, CdcrReplicationHandlerTest, TestRestoreCore, TestHighFrequencyDictionaryFactory, TestSuggestSpellingConverter, AutoscalingHistoryHandlerTest, TestSQLHandlerNonCloud, TestGeoJSONResponseWriter, TestMacros, MoreLikeThisHandlerTest, UUIDUpdateProcessorFallbackTest, SolrIndexSplitterTest, DistributedFacetPivotLongTailTest, WrapperMergePolicyFactoryTest, FileBasedSpellCheckerTest, StatelessScriptUpdateProcessorFactoryTest, TestConfigSetsAPIExclusivity, TestBlobHandler, HdfsAutoAddReplicasIntegrationTest, V2ApiIntegrationTest, BasicDistributedZk2Test, TestFieldCacheReopen, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestInitQParser, UpdateRequestProcessorFactoryTest, ReplaceNodeTest, DirectSolrSpellCheckerTest, InfoHandlerTest, TestQueryTypes, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, OpenCloseCoreStressTest, OverseerTest, LeaderElectionIntegrationTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler]
[junit4] Completed [386/751 (1!)] on J1 in 73.07s, 16 tests, 1 error, 1 skipped <<< FAILURES!
[...truncated 48580 lines...]