Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1207/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED:
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI
Error Message:
expected:<3> but was:<1>
Stack Trace:
java.lang.AssertionError: expected:<3> but was:<1>
at
__randomizedtesting.SeedInfo.seed([7E8EC62C8AAAA93E:36FBB2988C9986AB]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:523)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11919 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir:
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/init-core-data-001
[junit4] 2> 1434719 WARN
(SUITE-CollectionsAPIDistributedZkTest-seed#[7E8EC62C8AAAA93E]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=28 numCloses=28
[junit4] 2> 1434719 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[7E8EC62C8AAAA93E]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields
[junit4] 2> 1434721 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[7E8EC62C8AAAA93E]-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> 1434721 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[7E8EC62C8AAAA93E]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-001
[junit4] 2> 1434721 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[7E8EC62C8AAAA93E]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1434722 INFO (Thread-3185) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1434722 INFO (Thread-3185) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1434821 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[7E8EC62C8AAAA93E]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:60030
[junit4] 2> 1434829 INFO (jetty-launcher-2697-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1434829 INFO (jetty-launcher-2697-thread-2) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1434830 INFO (jetty-launcher-2697-thread-4) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1434830 INFO (jetty-launcher-2697-thread-3) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1434833 INFO (jetty-launcher-2697-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@3ee341f8{/solr,null,AVAILABLE}
[junit4] 2> 1434833 INFO (jetty-launcher-2697-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@5d4e694c{HTTP/1.1,[http/1.1]}{127.0.0.1:57014}
[junit4] 2> 1434833 INFO (jetty-launcher-2697-thread-2) [ ]
o.e.j.s.Server Started @1438861ms
[junit4] 2> 1434833 INFO (jetty-launcher-2697-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=57014}
[junit4] 2> 1434833 ERROR (jetty-launcher-2697-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1434833 INFO (jetty-launcher-2697-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 1434834 INFO (jetty-launcher-2697-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1434834 INFO (jetty-launcher-2697-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1434834 INFO (jetty-launcher-2697-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-03-21T01:21:25.450Z
[junit4] 2> 1434834 INFO (jetty-launcher-2697-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2ce46712{/solr,null,AVAILABLE}
[junit4] 2> 1434835 INFO (jetty-launcher-2697-thread-3) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@70f475b3{HTTP/1.1,[http/1.1]}{127.0.0.1:42240}
[junit4] 2> 1434835 INFO (jetty-launcher-2697-thread-3) [ ]
o.e.j.s.Server Started @1438863ms
[junit4] 2> 1434835 INFO (jetty-launcher-2697-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=42240}
[junit4] 2> 1434835 ERROR (jetty-launcher-2697-thread-3) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1434836 INFO (jetty-launcher-2697-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 1434836 INFO (jetty-launcher-2697-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1434836 INFO (jetty-launcher-2697-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1434836 INFO (jetty-launcher-2697-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1e3f4672{/solr,null,AVAILABLE}
[junit4] 2> 1434836 INFO (jetty-launcher-2697-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-03-21T01:21:25.452Z
[junit4] 2> 1434836 INFO (jetty-launcher-2697-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@79e0f6c7{HTTP/1.1,[http/1.1]}{127.0.0.1:49359}
[junit4] 2> 1434838 INFO (jetty-launcher-2697-thread-1) [ ]
o.e.j.s.Server Started @1438866ms
[junit4] 2> 1434838 INFO (jetty-launcher-2697-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=49359}
[junit4] 2> 1434838 ERROR (jetty-launcher-2697-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1434838 INFO (jetty-launcher-2697-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 1434838 INFO (jetty-launcher-2697-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@45b7ad24{/solr,null,AVAILABLE}
[junit4] 2> 1434838 INFO (jetty-launcher-2697-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1434838 INFO (jetty-launcher-2697-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1434838 INFO (jetty-launcher-2697-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-03-21T01:21:25.454Z
[junit4] 2> 1434839 INFO (jetty-launcher-2697-thread-4) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@73796b42{HTTP/1.1,[http/1.1]}{127.0.0.1:61782}
[junit4] 2> 1434839 INFO (jetty-launcher-2697-thread-4) [ ]
o.e.j.s.Server Started @1438867ms
[junit4] 2> 1434839 INFO (jetty-launcher-2697-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=61782}
[junit4] 2> 1434839 ERROR (jetty-launcher-2697-thread-4) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1434839 INFO (jetty-launcher-2697-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 1434839 INFO (jetty-launcher-2697-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1434839 INFO (jetty-launcher-2697-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1434839 INFO (jetty-launcher-2697-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-03-21T01:21:25.455Z
[junit4] 2> 1434842 INFO (jetty-launcher-2697-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1434844 INFO (jetty-launcher-2697-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1434844 INFO (jetty-launcher-2697-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1434844 INFO (jetty-launcher-2697-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1434852 INFO (jetty-launcher-2697-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60030/solr
[junit4] 2> 1434852 INFO (jetty-launcher-2697-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60030/solr
[junit4] 2> 1434853 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15aee74535b0003, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1434853 INFO (jetty-launcher-2697-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60030/solr
[junit4] 2> 1434857 INFO (jetty-launcher-2697-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60030/solr
[junit4] 2> 1434857 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15aee74535b0005, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1434892 INFO (jetty-launcher-2697-thread-2)
[n:127.0.0.1:57014_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1434892 INFO (jetty-launcher-2697-thread-4)
[n:127.0.0.1:61782_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1434892 INFO (jetty-launcher-2697-thread-3)
[n:127.0.0.1:42240_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1434893 INFO (jetty-launcher-2697-thread-1)
[n:127.0.0.1:49359_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1434894 INFO (jetty-launcher-2697-thread-2)
[n:127.0.0.1:57014_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:57014_solr
[junit4] 2> 1434894 INFO (jetty-launcher-2697-thread-3)
[n:127.0.0.1:42240_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42240_solr
[junit4] 2> 1434895 INFO (jetty-launcher-2697-thread-4)
[n:127.0.0.1:61782_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:61782_solr
[junit4] 2> 1434895 INFO (jetty-launcher-2697-thread-1)
[n:127.0.0.1:49359_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:49359_solr
[junit4] 2> 1434896 INFO (jetty-launcher-2697-thread-2)
[n:127.0.0.1:57014_solr ] o.a.s.c.Overseer Overseer
(id=97652525323976712-127.0.0.1:57014_solr-n_0000000000) starting
[junit4] 2> 1434898 INFO
(zkCallback-2718-thread-1-processing-n:127.0.0.1:42240_solr)
[n:127.0.0.1:42240_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 1434898 INFO
(zkCallback-2715-thread-1-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 1434899 INFO
(zkCallback-2717-thread-1-processing-n:127.0.0.1:61782_solr)
[n:127.0.0.1:61782_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 1434898 INFO
(zkCallback-2719-thread-1-processing-n:127.0.0.1:49359_solr)
[n:127.0.0.1:49359_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 1434904 INFO
(zkCallback-2715-thread-1-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 1434906 INFO
(zkCallback-2718-thread-1-processing-n:127.0.0.1:42240_solr)
[n:127.0.0.1:42240_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 1434906 INFO
(zkCallback-2717-thread-1-processing-n:127.0.0.1:61782_solr)
[n:127.0.0.1:61782_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 1434907 INFO
(zkCallback-2719-thread-1-processing-n:127.0.0.1:49359_solr)
[n:127.0.0.1:49359_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 1434913 INFO (jetty-launcher-2697-thread-2)
[n:127.0.0.1:57014_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:57014_solr
[junit4] 2> 1434924 INFO
(zkCallback-2715-thread-1-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1434924 INFO
(zkCallback-2719-thread-1-processing-n:127.0.0.1:49359_solr)
[n:127.0.0.1:49359_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1434924 INFO
(zkCallback-2718-thread-1-processing-n:127.0.0.1:42240_solr)
[n:127.0.0.1:42240_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1434924 INFO
(zkCallback-2717-thread-1-processing-n:127.0.0.1:61782_solr)
[n:127.0.0.1:61782_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1435038 INFO (jetty-launcher-2697-thread-3)
[n:127.0.0.1:42240_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-001/node4/.
[junit4] 2> 1435065 INFO (jetty-launcher-2697-thread-2)
[n:127.0.0.1:57014_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-001/node2/.
[junit4] 2> 1435121 INFO (jetty-launcher-2697-thread-4)
[n:127.0.0.1:61782_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-001/node3/.
[junit4] 2> 1435123 INFO (jetty-launcher-2697-thread-1)
[n:127.0.0.1:49359_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-001/node1/.
[junit4] 2> 1436455 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[7E8EC62C8AAAA93E]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1436457 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[7E8EC62C8AAAA93E]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60030/solr ready
[junit4] 2> 1436502 INFO
(TEST-CollectionsAPIDistributedZkTest.addReplicaTest-seed#[7E8EC62C8AAAA93E]) [
] o.a.s.SolrTestCaseJ4 ###Starting addReplicaTest
[junit4] 2> 1436504 INFO
(TEST-CollectionsAPIDistributedZkTest.addReplicaTest-seed#[7E8EC62C8AAAA93E]) [
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1436509 INFO (qtp968284010-15518) [n:127.0.0.1:57014_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=2&collection.configName=conf&maxShardsPerNode=4&name=addReplicaColl&action=CREATE&numShards=2&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1436513 INFO
(OverseerThreadFactory-6774-thread-1-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.CreateCollectionCmd Create collection
addReplicaColl
[junit4] 2> 1436624 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=addReplicaColl_shard2_replica1&action=CREATE&numShards=2&collection=addReplicaColl&shard=shard2&wt=javabin&version=2
[junit4] 2> 1436624 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=addReplicaColl_shard1_replica2&action=CREATE&numShards=2&collection=addReplicaColl&shard=shard1&wt=javabin&version=2
[junit4] 2> 1436629 INFO (qtp1179331080-15509) [n:127.0.0.1:49359_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=addReplicaColl_shard2_replica2&action=CREATE&numShards=2&collection=addReplicaColl&shard=shard2&wt=javabin&version=2
[junit4] 2> 1436629 INFO (qtp1173652184-15538) [n:127.0.0.1:61782_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=addReplicaColl_shard1_replica1&action=CREATE&numShards=2&collection=addReplicaColl&shard=shard1&wt=javabin&version=2
[junit4] 2> 1436741 INFO
(zkCallback-2717-thread-1-processing-n:127.0.0.1:61782_solr)
[n:127.0.0.1:61782_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1436741 INFO
(zkCallback-2719-thread-1-processing-n:127.0.0.1:49359_solr)
[n:127.0.0.1:49359_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1436741 INFO
(zkCallback-2715-thread-2-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1436742 INFO
(zkCallback-2718-thread-1-processing-n:127.0.0.1:42240_solr)
[n:127.0.0.1:42240_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1437642 INFO (qtp1173652184-15538) [n:127.0.0.1:61782_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1437642 INFO (qtp1179331080-15509) [n:127.0.0.1:49359_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica2] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1437642 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1437657 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1437658 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1]
o.a.s.s.IndexSchema [addReplicaColl_shard2_replica1] Schema name=minimal
[junit4] 2> 1437658 INFO (qtp1173652184-15538) [n:127.0.0.1:61782_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica1]
o.a.s.s.IndexSchema [addReplicaColl_shard1_replica1] Schema name=minimal
[junit4] 2> 1437661 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1437661 INFO (qtp1173652184-15538) [n:127.0.0.1:61782_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1437663 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1]
o.a.s.c.CoreContainer Creating SolrCore 'addReplicaColl_shard2_replica1' using
configuration from collection addReplicaColl
[junit4] 2> 1437663 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1437663 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1] o.a.s.c.SolrCore
[[addReplicaColl_shard2_replica1] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-001/node2/addReplicaColl_shard2_replica1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-001/node2/./addReplicaColl_shard2_replica1/data/]
[junit4] 2> 1437664 INFO (qtp1179331080-15509) [n:127.0.0.1:49359_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica2]
o.a.s.s.IndexSchema [addReplicaColl_shard2_replica2] Schema name=minimal
[junit4] 2> 1437665 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2]
o.a.s.s.IndexSchema [addReplicaColl_shard1_replica2] Schema name=minimal
[junit4] 2> 1437666 INFO (qtp1179331080-15509) [n:127.0.0.1:49359_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica2]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1437667 INFO (qtp1179331080-15509) [n:127.0.0.1:49359_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica2]
o.a.s.c.CoreContainer Creating SolrCore 'addReplicaColl_shard2_replica2' using
configuration from collection addReplicaColl
[junit4] 2> 1437667 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1437668 INFO (qtp1179331080-15509) [n:127.0.0.1:49359_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica2] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1437668 INFO (qtp1179331080-15509) [n:127.0.0.1:49359_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica2] o.a.s.c.SolrCore
[[addReplicaColl_shard2_replica2] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-001/node1/addReplicaColl_shard2_replica2],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-001/node1/./addReplicaColl_shard2_replica2/data/]
[junit4] 2> 1437668 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2]
o.a.s.c.CoreContainer Creating SolrCore 'addReplicaColl_shard1_replica2' using
configuration from collection addReplicaColl
[junit4] 2> 1437668 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1437668 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2] o.a.s.c.SolrCore
[[addReplicaColl_shard1_replica2] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-001/node4/addReplicaColl_shard1_replica2],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-001/node4/./addReplicaColl_shard1_replica2/data/]
[junit4] 2> 1437677 INFO (qtp1173652184-15538) [n:127.0.0.1:61782_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica1]
o.a.s.c.CoreContainer Creating SolrCore 'addReplicaColl_shard1_replica1' using
configuration from collection addReplicaColl
[junit4] 2> 1437678 INFO (qtp1173652184-15538) [n:127.0.0.1:61782_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1437678 INFO (qtp1173652184-15538) [n:127.0.0.1:61782_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica1] o.a.s.c.SolrCore
[[addReplicaColl_shard1_replica1] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-001/node3/addReplicaColl_shard1_replica1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-001/node3/./addReplicaColl_shard1_replica1/data/]
[junit4] 2> 1437724 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1437724 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1437725 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1437726 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1437726 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@7e92c0b6[addReplicaColl_shard2_replica1] main]
[junit4] 2> 1437728 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1437728 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1437728 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1437729 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1437729 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1437730 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@755fbce[addReplicaColl_shard1_replica2] main]
[junit4] 2> 1437730 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1437731 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1437731 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1562440408337743872
[junit4] 2> 1437733 INFO
(searcherExecutor-6783-thread-1-processing-n:127.0.0.1:57014_solr
x:addReplicaColl_shard2_replica1 s:shard2 c:addReplicaColl)
[n:127.0.0.1:57014_solr c:addReplicaColl s:shard2
x:addReplicaColl_shard2_replica1] o.a.s.c.SolrCore
[addReplicaColl_shard2_replica1] Registered new searcher
Searcher@7e92c0b6[addReplicaColl_shard2_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1437733 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1437734 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1437734 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1437734 INFO
(searcherExecutor-6785-thread-1-processing-n:127.0.0.1:42240_solr
x:addReplicaColl_shard1_replica2 s:shard1 c:addReplicaColl)
[n:127.0.0.1:42240_solr c:addReplicaColl s:shard1
x:addReplicaColl_shard1_replica2] o.a.s.c.SolrCore
[addReplicaColl_shard1_replica2] Registered new searcher
Searcher@755fbce[addReplicaColl_shard1_replica2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1437735 INFO (qtp1173652184-15538) [n:127.0.0.1:61782_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1437735 INFO (qtp1173652184-15538) [n:127.0.0.1:61782_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1437735 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1562440408341938176
[junit4] 2> 1437736 INFO (qtp1173652184-15538) [n:127.0.0.1:61782_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1437736 INFO (qtp1173652184-15538) [n:127.0.0.1:61782_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1437737 INFO (qtp1173652184-15538) [n:127.0.0.1:61782_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@4e5803a[addReplicaColl_shard1_replica1] main]
[junit4] 2> 1437740 INFO (qtp1173652184-15538) [n:127.0.0.1:61782_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1437741 INFO (qtp1173652184-15538) [n:127.0.0.1:61782_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1437741 INFO (qtp1173652184-15538) [n:127.0.0.1:61782_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1437741 INFO (qtp1173652184-15538) [n:127.0.0.1:61782_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1562440408348229632
[junit4] 2> 1437742 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for
shard shard2: total=2 found=1 timeoutin=9999ms
[junit4] 2> 1437742 INFO
(searcherExecutor-6786-thread-1-processing-n:127.0.0.1:61782_solr
x:addReplicaColl_shard1_replica1 s:shard1 c:addReplicaColl)
[n:127.0.0.1:61782_solr c:addReplicaColl s:shard1
x:addReplicaColl_shard1_replica1] o.a.s.c.SolrCore
[addReplicaColl_shard1_replica1] Registered new searcher
Searcher@4e5803a[addReplicaColl_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1437746 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2]
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for
shard shard1: total=2 found=1 timeoutin=9999ms
[junit4] 2> 1437767 INFO (qtp1179331080-15509) [n:127.0.0.1:49359_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica2]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1437767 INFO (qtp1179331080-15509) [n:127.0.0.1:49359_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica2] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1437768 INFO (qtp1179331080-15509) [n:127.0.0.1:49359_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica2]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1437768 INFO (qtp1179331080-15509) [n:127.0.0.1:49359_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica2]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1437768 INFO (qtp1179331080-15509) [n:127.0.0.1:49359_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@139a0a42[addReplicaColl_shard2_replica2] main]
[junit4] 2> 1437770 INFO (qtp1179331080-15509) [n:127.0.0.1:49359_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica2]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1437770 INFO (qtp1179331080-15509) [n:127.0.0.1:49359_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica2]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1437770 INFO (qtp1179331080-15509) [n:127.0.0.1:49359_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica2]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1437771 INFO (qtp1179331080-15509) [n:127.0.0.1:49359_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica2] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1562440408379686912
[junit4] 2> 1437771 INFO
(searcherExecutor-6784-thread-1-processing-n:127.0.0.1:49359_solr
x:addReplicaColl_shard2_replica2 s:shard2 c:addReplicaColl)
[n:127.0.0.1:49359_solr c:addReplicaColl s:shard2
x:addReplicaColl_shard2_replica2] o.a.s.c.SolrCore
[addReplicaColl_shard2_replica2] Registered new searcher
Searcher@139a0a42[addReplicaColl_shard2_replica2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1437849 INFO
(zkCallback-2718-thread-1-processing-n:127.0.0.1:42240_solr)
[n:127.0.0.1:42240_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1437849 INFO
(zkCallback-2719-thread-1-processing-n:127.0.0.1:49359_solr)
[n:127.0.0.1:49359_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1437850 INFO
(zkCallback-2715-thread-2-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1437850 INFO
(zkCallback-2717-thread-1-processing-n:127.0.0.1:61782_solr)
[n:127.0.0.1:61782_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1438245 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1438245 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1438245 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:57014/solr/addReplicaColl_shard2_replica1/
[junit4] 2> 1438246 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1] o.a.s.u.PeerSync
PeerSync: core=addReplicaColl_shard2_replica1 url=http://127.0.0.1:57014/solr
START replicas=[http://127.0.0.1:49359/solr/addReplicaColl_shard2_replica2/]
nUpdates=100
[junit4] 2> 1438246 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1] o.a.s.u.PeerSync
PeerSync: core=addReplicaColl_shard2_replica1 url=http://127.0.0.1:57014/solr
DONE. We have no versions. sync failed.
[junit4] 2> 1438247 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1438247 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1438247 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:42240/solr/addReplicaColl_shard1_replica2/
[junit4] 2> 1438247 INFO (qtp1179331080-15511) [n:127.0.0.1:49359_solr
c:addReplicaColl s:shard2 r:core_node3 x:addReplicaColl_shard2_replica2]
o.a.s.c.S.Request [addReplicaColl_shard2_replica2] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1438248 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2] o.a.s.u.PeerSync
PeerSync: core=addReplicaColl_shard1_replica2 url=http://127.0.0.1:42240/solr
START replicas=[http://127.0.0.1:61782/solr/addReplicaColl_shard1_replica1/]
nUpdates=100
[junit4] 2> 1438248 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2] o.a.s.u.PeerSync
PeerSync: core=addReplicaColl_shard1_replica2 url=http://127.0.0.1:42240/solr
DONE. We have no versions. sync failed.
[junit4] 2> 1438248 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1]
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the
next candidate
[junit4] 2> 1438248 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we
can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 1438248 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1438249 INFO (qtp1173652184-15539) [n:127.0.0.1:61782_solr
c:addReplicaColl s:shard1 r:core_node4 x:addReplicaColl_shard1_replica1]
o.a.s.c.S.Request [addReplicaColl_shard1_replica1] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1438250 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2]
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the
next candidate
[junit4] 2> 1438250 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2]
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we
can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 1438250 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1438257 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:42240/solr/addReplicaColl_shard1_replica2/ shard1
[junit4] 2> 1438257 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:57014/solr/addReplicaColl_shard2_replica1/ shard2
[junit4] 2> 1438364 INFO
(zkCallback-2715-thread-3-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1438364 INFO
(zkCallback-2719-thread-1-processing-n:127.0.0.1:49359_solr)
[n:127.0.0.1:49359_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1438364 INFO
(zkCallback-2717-thread-1-processing-n:127.0.0.1:61782_solr)
[n:127.0.0.1:61782_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1438364 INFO
(zkCallback-2718-thread-1-processing-n:127.0.0.1:42240_solr)
[n:127.0.0.1:42240_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1438410 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1438410 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1438413 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica2]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=addReplicaColl_shard1_replica2&action=CREATE&numShards=2&collection=addReplicaColl&shard=shard1&wt=javabin&version=2}
status=0 QTime=1790
[junit4] 2> 1438414 INFO (qtp968284010-15520) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=addReplicaColl_shard2_replica1&action=CREATE&numShards=2&collection=addReplicaColl&shard=shard2&wt=javabin&version=2}
status=0 QTime=1791
[junit4] 2> 1438519 INFO
(zkCallback-2715-thread-2-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1438519 INFO
(zkCallback-2719-thread-1-processing-n:127.0.0.1:49359_solr)
[n:127.0.0.1:49359_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1438520 INFO
(zkCallback-2718-thread-1-processing-n:127.0.0.1:42240_solr)
[n:127.0.0.1:42240_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1438520 INFO
(zkCallback-2717-thread-1-processing-n:127.0.0.1:61782_solr)
[n:127.0.0.1:61782_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1438752 INFO (qtp1173652184-15538) [n:127.0.0.1:61782_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=addReplicaColl_shard1_replica1&action=CREATE&numShards=2&collection=addReplicaColl&shard=shard1&wt=javabin&version=2}
status=0 QTime=2124
[junit4] 2> 1438780 INFO (qtp1179331080-15509) [n:127.0.0.1:49359_solr
c:addReplicaColl s:shard2 x:addReplicaColl_shard2_replica2]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=addReplicaColl_shard2_replica2&action=CREATE&numShards=2&collection=addReplicaColl&shard=shard2&wt=javabin&version=2}
status=0 QTime=2152
[junit4] 2> 1438786 INFO (qtp968284010-15518) [n:127.0.0.1:57014_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> 1438886 INFO
(zkCallback-2715-thread-3-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1438886 INFO
(zkCallback-2717-thread-1-processing-n:127.0.0.1:61782_solr)
[n:127.0.0.1:61782_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1438886 INFO
(zkCallback-2718-thread-1-processing-n:127.0.0.1:42240_solr)
[n:127.0.0.1:42240_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1438886 INFO
(zkCallback-2719-thread-1-processing-n:127.0.0.1:49359_solr)
[n:127.0.0.1:49359_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1439787 INFO (qtp968284010-15518) [n:127.0.0.1:57014_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=2&collection.configName=conf&maxShardsPerNode=4&name=addReplicaColl&action=CREATE&numShards=2&wt=javabin&version=2}
status=0 QTime=3280
[junit4] 2> 1439789 INFO (qtp968284010-15521) [n:127.0.0.1:57014_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with
params
node=127.0.0.1:57014_solr&action=ADDREPLICA&collection=addReplicaColl&shard=shard1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1439793 INFO
(OverseerThreadFactory-6774-thread-2-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.AddReplicaCmd addReplica() : {
[junit4] 2> "collection":"addReplicaColl",
[junit4] 2> "node":"127.0.0.1:57014_solr",
[junit4] 2> "shard":"shard1",
[junit4] 2> "operation":"addreplica"}
[junit4] 2> 1439793 INFO
(OverseerThreadFactory-6774-thread-2-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.AddReplicaCmd Node Identified
127.0.0.1:57014_solr for creating new replica
[junit4] 2> 1439794 INFO
(OverseerCollectionConfigSetProcessor-97652525323976712-127.0.0.1:57014_solr-n_0000000000)
[n:127.0.0.1:57014_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> 1439795 INFO (qtp968284010-15522) [n:127.0.0.1:57014_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&name=addReplicaColl_shard1_replica3&action=CREATE&collection=addReplicaColl&shard=shard1&wt=javabin&version=2
[junit4] 2> 1439901 INFO
(zkCallback-2717-thread-1-processing-n:127.0.0.1:61782_solr)
[n:127.0.0.1:61782_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1439901 INFO
(zkCallback-2718-thread-1-processing-n:127.0.0.1:42240_solr)
[n:127.0.0.1:42240_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1439901 INFO
(zkCallback-2719-thread-1-processing-n:127.0.0.1:49359_solr)
[n:127.0.0.1:49359_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1439903 INFO
(zkCallback-2715-thread-1-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1440806 INFO (qtp968284010-15522) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica3] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1440826 INFO (qtp968284010-15522) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica3]
o.a.s.s.IndexSchema [addReplicaColl_shard1_replica3] Schema name=minimal
[junit4] 2> 1440828 INFO (qtp968284010-15522) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica3]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1440829 INFO (qtp968284010-15522) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica3]
o.a.s.c.CoreContainer Creating SolrCore 'addReplicaColl_shard1_replica3' using
configuration from collection addReplicaColl
[junit4] 2> 1440829 INFO (qtp968284010-15522) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica3] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1440829 INFO (qtp968284010-15522) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica3] o.a.s.c.SolrCore
[[addReplicaColl_shard1_replica3] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-001/node2/addReplicaColl_shard1_replica3],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-001/node2/./addReplicaColl_shard1_replica3/data/]
[junit4] 2> 1440872 INFO (qtp968284010-15522) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica3]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1440872 INFO (qtp968284010-15522) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica3] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1440873 INFO (qtp968284010-15522) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica3]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1440873 INFO (qtp968284010-15522) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica3]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1440873 INFO (qtp968284010-15522) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica3]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@6acc9579[addReplicaColl_shard1_replica3] main]
[junit4] 2> 1440875 INFO (qtp968284010-15522) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica3]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1440876 INFO (qtp968284010-15522) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica3]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1440876 INFO (qtp968284010-15522) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica3]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1440876 INFO
(searcherExecutor-6803-thread-1-processing-n:127.0.0.1:57014_solr
x:addReplicaColl_shard1_replica3 s:shard1 c:addReplicaColl)
[n:127.0.0.1:57014_solr c:addReplicaColl s:shard1
x:addReplicaColl_shard1_replica3] o.a.s.c.SolrCore
[addReplicaColl_shard1_replica3] Registered new searcher
Searcher@6acc9579[addReplicaColl_shard1_replica3]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1440876 INFO (qtp968284010-15522) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica3] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1562440411635515392
[junit4] 2> 1440880 INFO (qtp968284010-15522) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica3]
o.a.s.c.ZkController Core needs to recover:addReplicaColl_shard1_replica3
[junit4] 2> 1440881 INFO
(updateExecutor-2706-thread-1-processing-n:127.0.0.1:57014_solr
x:addReplicaColl_shard1_replica3 s:shard1 c:addReplicaColl)
[n:127.0.0.1:57014_solr c:addReplicaColl s:shard1 r:core_node5
x:addReplicaColl_shard1_replica3] o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 1440881 INFO (qtp968284010-15522) [n:127.0.0.1:57014_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica3]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&name=addReplicaColl_shard1_replica3&action=CREATE&collection=addReplicaColl&shard=shard1&wt=javabin&version=2}
status=0 QTime=1087
[junit4] 2> 1440882 INFO
(recoveryExecutor-2707-thread-1-processing-n:127.0.0.1:57014_solr
x:addReplicaColl_shard1_replica3 s:shard1 c:addReplicaColl r:core_node5)
[n:127.0.0.1:57014_solr c:addReplicaColl s:shard1 r:core_node5
x:addReplicaColl_shard1_replica3] o.a.s.c.RecoveryStrategy Starting recovery
process. recoveringAfterStartup=true
[junit4] 2> 1440883 INFO
(recoveryExecutor-2707-thread-1-processing-n:127.0.0.1:57014_solr
x:addReplicaColl_shard1_replica3 s:shard1 c:addReplicaColl r:core_node5)
[n:127.0.0.1:57014_solr c:addReplicaColl s:shard1 r:core_node5
x:addReplicaColl_shard1_replica3] o.a.s.c.RecoveryStrategy ######
startupVersions=[[]]
[junit4] 2> 1440883 INFO
(recoveryExecutor-2707-thread-1-processing-n:127.0.0.1:57014_solr
x:addReplicaColl_shard1_replica3 s:shard1 c:addReplicaColl r:core_node5)
[n:127.0.0.1:57014_solr c:addReplicaColl s:shard1 r:core_node5
x:addReplicaColl_shard1_replica3] o.a.s.c.RecoveryStrategy Begin buffering
updates. core=[addReplicaColl_shard1_replica3]
[junit4] 2> 1440883 INFO
(recoveryExecutor-2707-thread-1-processing-n:127.0.0.1:57014_solr
x:addReplicaColl_shard1_replica3 s:shard1 c:addReplicaColl r:core_node5)
[n:127.0.0.1:57014_solr c:addReplicaColl s:shard1 r:core_node5
x:addReplicaColl_shard1_replica3] o.a.s.u.UpdateLog Starting to buffer updates.
FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 1440883 INFO
(recoveryExecutor-2707-thread-1-processing-n:127.0.0.1:57014_solr
x:addReplicaColl_shard1_replica3 s:shard1 c:addReplicaColl r:core_node5)
[n:127.0.0.1:57014_solr c:addReplicaColl s:shard1 r:core_node5
x:addReplicaColl_shard1_replica3] o.a.s.c.RecoveryStrategy Publishing state of
core [addReplicaColl_shard1_replica3] as recovering, leader is
[http://127.0.0.1:42240/solr/addReplicaColl_shard1_replica2/] and I am
[http://127.0.0.1:57014/solr/addReplicaColl_shard1_replica3/]
[junit4] 2> 1440886 INFO (qtp968284010-15521) [n:127.0.0.1:57014_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:57014_solr&action=ADDREPLICA&collection=addReplicaColl&shard=shard1&wt=javabin&version=2}
status=0 QTime=1097
[junit4] 2> 1440887 INFO
(recoveryExecutor-2707-thread-1-processing-n:127.0.0.1:57014_solr
x:addReplicaColl_shard1_replica3 s:shard1 c:addReplicaColl r:core_node5)
[n:127.0.0.1:57014_solr c:addReplicaColl s:shard1 r:core_node5
x:addReplicaColl_shard1_replica3] o.a.s.c.RecoveryStrategy Sending prep
recovery command to [http://127.0.0.1:42240/solr]; [WaitForState:
action=PREPRECOVERY&core=addReplicaColl_shard1_replica2&nodeName=127.0.0.1:57014_solr&coreNodeName=core_node5&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 1440888 INFO (qtp968284010-15516) [n:127.0.0.1:57014_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with
params
action=ADDREPLICA&collection=addReplicaColl&shard=shard1&wt=javabin&version=2&property.instanceDir=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-002
and sendToOCPQueue=true
[junit4] 2> 1440889 INFO (qtp1986004483-15531) [n:127.0.0.1:42240_solr
] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node5, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 1440890 INFO (qtp1986004483-15531) [n:127.0.0.1:42240_solr
] o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see
addReplicaColl_shard1_replica2 (shard1 of addReplicaColl) have state: recovering
[junit4] 2> 1440890 INFO (qtp1986004483-15531) [n:127.0.0.1:42240_solr
] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering):
collection=addReplicaColl, shard=shard1,
thisCore=addReplicaColl_shard1_replica2, leaderDoesNotNeedRecovery=false,
isLeader? true, live=true, checkLive=true, currentState=down,
localState=active, nodeName=127.0.0.1:57014_solr, coreNodeName=core_node5,
onlyIfActiveCheckResult=false, nodeProps:
core_node5:{"core":"addReplicaColl_shard1_replica3","base_url":"http://127.0.0.1:57014/solr","node_name":"127.0.0.1:57014_solr","state":"down"}
[junit4] 2> 1440892 INFO
(OverseerThreadFactory-6774-thread-3-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.AddReplicaCmd addReplica() : {
[junit4] 2> "collection":"addReplicaColl",
[junit4] 2> "shard":"shard1",
[junit4] 2>
"property.instanceDir":"/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-002",
[junit4] 2> "operation":"addreplica"}
[junit4] 2> 1440892 INFO
(OverseerThreadFactory-6774-thread-3-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.AddReplicaCmd Node Identified
127.0.0.1:42240_solr for creating new replica
[junit4] 2> 1440893 INFO
(OverseerCollectionConfigSetProcessor-97652525323976712-127.0.0.1:57014_solr-n_0000000000)
[n:127.0.0.1:57014_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> 1440894 INFO (qtp1986004483-15529) [n:127.0.0.1:42240_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&name=addReplicaColl_shard1_replica4&action=CREATE&collection=addReplicaColl&shard=shard1&wt=javabin&version=2&property.instanceDir=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-002
[junit4] 2> 1440999 INFO
(zkCallback-2715-thread-2-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1441000 INFO
(zkCallback-2719-thread-1-processing-n:127.0.0.1:49359_solr)
[n:127.0.0.1:49359_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1441000 INFO
(zkCallback-2717-thread-1-processing-n:127.0.0.1:61782_solr)
[n:127.0.0.1:61782_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1441000 INFO
(zkCallback-2718-thread-1-processing-n:127.0.0.1:42240_solr)
[n:127.0.0.1:42240_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1441891 INFO (qtp1986004483-15531) [n:127.0.0.1:42240_solr
] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering):
collection=addReplicaColl, shard=shard1,
thisCore=addReplicaColl_shard1_replica2, leaderDoesNotNeedRecovery=false,
isLeader? true, live=true, checkLive=true, currentState=recovering,
localState=active, nodeName=127.0.0.1:57014_solr, coreNodeName=core_node5,
onlyIfActiveCheckResult=false, nodeProps:
core_node5:{"core":"addReplicaColl_shard1_replica3","base_url":"http://127.0.0.1:57014/solr","node_name":"127.0.0.1:57014_solr","state":"recovering"}
[junit4] 2> 1441891 INFO (qtp1986004483-15531) [n:127.0.0.1:42240_solr
] o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node5, state: recovering,
checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 1441891 INFO (qtp1986004483-15531) [n:127.0.0.1:42240_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:57014_solr&onlyIfLeaderActive=true&core=addReplicaColl_shard1_replica2&coreNodeName=core_node5&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=1002
[junit4] 2> 1441904 INFO (qtp1986004483-15529) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica4] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1441910 INFO (qtp1986004483-15529) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica4]
o.a.s.s.IndexSchema [addReplicaColl_shard1_replica4] Schema name=minimal
[junit4] 2> 1441912 INFO (qtp1986004483-15529) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica4]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1441913 INFO (qtp1986004483-15529) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica4]
o.a.s.c.CoreContainer Creating SolrCore 'addReplicaColl_shard1_replica4' using
configuration from collection addReplicaColl
[junit4] 2> 1441914 INFO (qtp1986004483-15529) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica4] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1441914 INFO (qtp1986004483-15529) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica4] o.a.s.c.SolrCore
[[addReplicaColl_shard1_replica4] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-002],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-002/data/]
[junit4] 2> 1441957 INFO (qtp1986004483-15529) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica4]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1441957 INFO (qtp1986004483-15529) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica4] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1441959 INFO (qtp1986004483-15529) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica4]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1441959 INFO (qtp1986004483-15529) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica4]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1441960 INFO (qtp1986004483-15529) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica4]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@32573949[addReplicaColl_shard1_replica4] main]
[junit4] 2> 1441961 INFO (qtp1986004483-15529) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica4]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1441962 INFO (qtp1986004483-15529) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica4]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1441962 INFO (qtp1986004483-15529) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica4]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1441962 INFO
(searcherExecutor-6808-thread-1-processing-n:127.0.0.1:42240_solr
x:addReplicaColl_shard1_replica4 s:shard1 c:addReplicaColl)
[n:127.0.0.1:42240_solr c:addReplicaColl s:shard1
x:addReplicaColl_shard1_replica4] o.a.s.c.SolrCore
[addReplicaColl_shard1_replica4] Registered new searcher
Searcher@32573949[addReplicaColl_shard1_replica4]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1441962 INFO (qtp1986004483-15529) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica4] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1562440412774268928
[junit4] 2> 1441966 INFO (qtp1986004483-15529) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica4]
o.a.s.c.ZkController Core needs to recover:addReplicaColl_shard1_replica4
[junit4] 2> 1441967 INFO
(updateExecutor-2704-thread-1-processing-n:127.0.0.1:42240_solr
x:addReplicaColl_shard1_replica4 s:shard1 c:addReplicaColl)
[n:127.0.0.1:42240_solr c:addReplicaColl s:shard1 r:core_node6
x:addReplicaColl_shard1_replica4] o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 1441968 INFO
(recoveryExecutor-2705-thread-1-processing-n:127.0.0.1:42240_solr
x:addReplicaColl_shard1_replica4 s:shard1 c:addReplicaColl r:core_node6)
[n:127.0.0.1:42240_solr c:addReplicaColl s:shard1 r:core_node6
x:addReplicaColl_shard1_replica4] o.a.s.c.RecoveryStrategy Starting recovery
process. recoveringAfterStartup=true
[junit4] 2> 1441968 INFO
(recoveryExecutor-2705-thread-1-processing-n:127.0.0.1:42240_solr
x:addReplicaColl_shard1_replica4 s:shard1 c:addReplicaColl r:core_node6)
[n:127.0.0.1:42240_solr c:addReplicaColl s:shard1 r:core_node6
x:addReplicaColl_shard1_replica4] o.a.s.c.RecoveryStrategy ######
startupVersions=[[]]
[junit4] 2> 1441968 INFO (qtp1986004483-15529) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 x:addReplicaColl_shard1_replica4]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&name=addReplicaColl_shard1_replica4&action=CREATE&collection=addReplicaColl&shard=shard1&wt=javabin&version=2&property.instanceDir=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-002}
status=0 QTime=1074
[junit4] 2> 1441968 INFO
(recoveryExecutor-2705-thread-1-processing-n:127.0.0.1:42240_solr
x:addReplicaColl_shard1_replica4 s:shard1 c:addReplicaColl r:core_node6)
[n:127.0.0.1:42240_solr c:addReplicaColl s:shard1 r:core_node6
x:addReplicaColl_shard1_replica4] o.a.s.c.RecoveryStrategy Begin buffering
updates. core=[addReplicaColl_shard1_replica4]
[junit4] 2> 1441968 INFO
(recoveryExecutor-2705-thread-1-processing-n:127.0.0.1:42240_solr
x:addReplicaColl_shard1_replica4 s:shard1 c:addReplicaColl r:core_node6)
[n:127.0.0.1:42240_solr c:addReplicaColl s:shard1 r:core_node6
x:addReplicaColl_shard1_replica4] o.a.s.u.UpdateLog Starting to buffer updates.
FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 1441968 INFO
(recoveryExecutor-2705-thread-1-processing-n:127.0.0.1:42240_solr
x:addReplicaColl_shard1_replica4 s:shard1 c:addReplicaColl r:core_node6)
[n:127.0.0.1:42240_solr c:addReplicaColl s:shard1 r:core_node6
x:addReplicaColl_shard1_replica4] o.a.s.c.RecoveryStrategy Publishing state of
core [addReplicaColl_shard1_replica4] as recovering, leader is
[http://127.0.0.1:42240/solr/addReplicaColl_shard1_replica2/] and I am
[http://127.0.0.1:42240/solr/addReplicaColl_shard1_replica4/]
[junit4] 2> 1441970 INFO (qtp968284010-15516) [n:127.0.0.1:57014_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=ADDREPLICA&collection=addReplicaColl&shard=shard1&wt=javabin&version=2&property.instanceDir=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001/tempDir-002}
status=0 QTime=1082
[junit4] 2> 1441973 INFO
(recoveryExecutor-2705-thread-1-processing-n:127.0.0.1:42240_solr
x:addReplicaColl_shard1_replica4 s:shard1 c:addReplicaColl r:core_node6)
[n:127.0.0.1:42240_solr c:addReplicaColl s:shard1 r:core_node6
x:addReplicaColl_shard1_replica4] o.a.s.c.RecoveryStrategy Sending prep
recovery command to [http://127.0.0.1:42240/solr]; [WaitForState:
action=PREPRECOVERY&core=addReplicaColl_shard1_replica2&nodeName=127.0.0.1:42240_solr&coreNodeName=core_node6&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 1441973 INFO (qtp1986004483-15526) [n:127.0.0.1:42240_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={core=addReplicaColl_shard1_replica4&action=STATUS&indexInfo=true&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1441974 INFO (qtp1986004483-15531) [n:127.0.0.1:42240_solr
] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node6, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 1441974 INFO (qtp1986004483-15531) [n:127.0.0.1:42240_solr
] o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see
addReplicaColl_shard1_replica2 (shard1 of addReplicaColl) have state: recovering
[junit4] 2> 1441975 INFO (qtp1986004483-15531) [n:127.0.0.1:42240_solr
] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering):
collection=addReplicaColl, shard=shard1,
thisCore=addReplicaColl_shard1_replica2, leaderDoesNotNeedRecovery=false,
isLeader? true, live=true, checkLive=true, currentState=down,
localState=active, nodeName=127.0.0.1:42240_solr, coreNodeName=core_node6,
onlyIfActiveCheckResult=false, nodeProps:
core_node6:{"core":"addReplicaColl_shard1_replica4","base_url":"http://127.0.0.1:42240/solr","node_name":"127.0.0.1:42240_solr","state":"down"}
[junit4] 2> 1441975 INFO (qtp968284010-15515) [n:127.0.0.1:57014_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with
params
name=addReplicaColl_shard1_replica4&action=addreplica&collection=addReplicaColl&shard=shard1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1441979 INFO
(OverseerThreadFactory-6774-thread-4-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.AddReplicaCmd addReplica() : {
[junit4] 2> "collection":"addReplicaColl",
[junit4] 2> "shard":"shard1",
[junit4] 2> "name":"addReplicaColl_shard1_replica4",
[junit4] 2> "operation":"addreplica"}
[junit4] 2> 1441979 INFO
(OverseerThreadFactory-6774-thread-4-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.AddReplicaCmd Node Identified
127.0.0.1:49359_solr for creating new replica
[junit4] 2> 1441979 ERROR
(OverseerThreadFactory-6774-thread-4-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.OverseerCollectionMessageHandler
Collection: addReplicaColl operation: addreplica
failed:org.apache.solr.common.SolrException: Another replica with the same core
name already exists for this collection
[junit4] 2> at
org.apache.solr.cloud.AddReplicaCmd.addReplica(AddReplicaCmd.java:111)
[junit4] 2> at
org.apache.solr.cloud.AddReplicaCmd.call(AddReplicaCmd.java:65)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:225)
[junit4] 2> at
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:463)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 1441979 INFO
(OverseerCollectionConfigSetProcessor-97652525323976712-127.0.0.1:57014_solr-n_0000000000)
[n:127.0.0.1:57014_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1441983 INFO (qtp968284010-15515) [n:127.0.0.1:57014_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=addReplicaColl_shard1_replica4&action=addreplica&collection=addReplicaColl&shard=shard1&wt=javabin&version=2}
status=400 QTime=7
[junit4] 2> 1441985 INFO (qtp968284010-15519) [n:127.0.0.1:57014_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with
params
action=ADDREPLICA&collection=addReplicaColl&shard=shard1&property.name=propertyDotName&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1441988 INFO
(OverseerThreadFactory-6774-thread-5-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.AddReplicaCmd addReplica() : {
[junit4] 2> "collection":"addReplicaColl",
[junit4] 2> "shard":"shard1",
[junit4] 2> "property.name":"propertyDotName",
[junit4] 2> "operation":"addreplica"}
[junit4] 2> 1441988 INFO
(OverseerThreadFactory-6774-thread-5-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.AddReplicaCmd Node Identified
127.0.0.1:49359_solr for creating new replica
[junit4] 2> 1441989 INFO
(OverseerCollectionConfigSetProcessor-97652525323976712-127.0.0.1:57014_solr-n_0000000000)
[n:127.0.0.1:57014_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1441992 INFO (qtp1179331080-15512) [n:127.0.0.1:49359_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&name=propertyDotName&action=CREATE&collection=addReplicaColl&shard=shard1&property.name=propertyDotName&wt=javabin&version=2
[junit4] 2> 1442102 INFO
(zkCallback-2717-thread-1-processing-n:127.0.0.1:61782_solr)
[n:127.0.0.1:61782_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1442102 INFO
(zkCallback-2718-thread-1-processing-n:127.0.0.1:42240_solr)
[n:127.0.0.1:42240_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1442102 INFO
(zkCallback-2715-thread-1-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1442102 INFO
(zkCallback-2719-thread-1-processing-n:127.0.0.1:49359_solr)
[n:127.0.0.1:49359_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1442392 INFO
(recoveryExecutor-2707-thread-1-processing-n:127.0.0.1:57014_solr
x:addReplicaColl_shard1_replica3 s:shard1 c:addReplicaColl r:core_node5)
[n:127.0.0.1:57014_solr c:addReplicaColl s:shard1 r:core_node5
x:addReplicaColl_shard1_replica3] o.a.s.c.RecoveryStrategy Attempting to
PeerSync from [http://127.0.0.1:42240/solr/addReplicaColl_shard1_replica2/] -
recoveringAfterStartup=[true]
[junit4] 2> 1442392 INFO
(recoveryExecutor-2707-thread-1-processing-n:127.0.0.1:57014_solr
x:addReplicaColl_shard1_replica3 s:shard1 c:addReplicaColl r:core_node5)
[n:127.0.0.1:57014_solr c:addReplicaColl s:shard1 r:core_node5
x:addReplicaColl_shard1_replica3] o.a.s.u.PeerSync PeerSync:
core=addReplicaColl_shard1_replica3 url=http://127.0.0.1:57014/solr START
replicas=[http://127.0.0.1:42240/solr/addReplicaColl_shard1_replica2/]
nUpdates=100
[junit4] 2> 1442394 INFO (qtp1986004483-15530) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 r:core_node2 x:addReplicaColl_shard1_replica2]
o.a.s.u.IndexFingerprint IndexFingerprint millis:0.0
result:{maxVersionSpecified=9223372036854775807, maxVersionEncountered=0,
maxInHash=0, versionsHash=0, numVersions=0, numDocs=0, maxDoc=0}
[junit4] 2> 1442394 INFO (qtp1986004483-15530) [n:127.0.0.1:42240_solr
c:addReplicaColl s:shard1 r:core_node2 x:addReplicaColl_shard1_replica2]
o.a.s.c.S.Request [addReplicaColl_shard1_replica2] webapp=/solr path=/get
params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1442394 INFO
(recoveryExecutor-2707-thread-1-processing-n:127.0.0.1:57014_solr
x:addReplicaColl_shard1_replica3 s:shard1 c:addReplicaColl r:core_node5)
[n:127.0.0.1:57014_solr c:addReplicaColl s:shard1 r:core_node5
x:addReplicaColl_shard1_replica3] o.a.s.u.IndexFingerprint IndexFingerprint
millis:0.0 result:{maxVersionSpecified=9223372036854775807,
maxVersionEncountered=0, maxInHash=0, versionsHash=0, numVersions=0, numDocs=0,
maxDoc=0}
[junit4] 2> 1442395 INFO
(recoveryExecutor-2707-thread-1-processing-n:127.0.0.1:57014_solr
x:addReplicaColl_shard1_replica3 s:shard1 c:addReplicaColl r:core_node5)
[n:127.0.0.1:57014_solr c:addReplicaColl s:shard1 r:core_node5
x:addReplicaColl_shard1_replica3] o.a.s.u.PeerSync We are already in sync. No
need to do a PeerSync
[junit4] 2> 1442395 INFO
(recoveryExecutor-2707-thread-1-processing-n:127.0.0.1:57014_solr
x:addReplicaColl_shard1_replica3 s:shard1 c:addReplicaColl r:core_node5)
[n:127.0.0.1:57014_solr c:addReplicaColl s:shard1 r:core_node5
x:addReplicaColl_shard1_replica3] o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1442395 INFO
(recoveryExecutor-2707-thread-1-processing-n:127.0.0.1:57014_solr
x:addReplicaColl_shard1_replica3 s:shard1 c:addReplicaColl r:core_node5)
[n:127.0.0.1:57014_solr c:addReplicaColl s:shard1 r:core_node5
x:addReplicaColl_shard1_replica3] o.a.s.u.DirectUpdateHandler2 No uncommitted
changes. Skipping IW.commit.
[junit4] 2> 1442396 INFO
(recoveryExecutor-2707-thread-1-processing-n:127.0.0.1:57014_solr
x:addReplicaColl_shard1_replica3 s:shard1 c:addReplicaColl r:core_node5)
[n:127.0.0.1:57014_solr c:addReplicaColl s:shard1 r:core_node5
x:addReplicaColl_shard1_replica3] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1442396 INFO
(recoveryExecutor-2707-thread-1-processing-n:127.0.0.1:57014_solr
x:addReplicaColl_shard1_replica3 s:shard1 c:addReplicaColl r:core_node5)
[n:127.0.0.1:57014_solr c:addReplicaColl s:shard1 r:core_node5
x:addReplicaColl_shard1_replica3] o.a.s.c.RecoveryStrategy PeerSync stage of
recovery was successful.
[junit4] 2> 1442396 INFO
(recoveryExecutor-2707-thread-1-processing-n:127.0.0.1:57014_solr
x:addReplicaColl_shard1_replica3 s:shard1 c:addReplicaColl r:core_node5)
[n:127.0.0.1:57014_solr c:addReplicaColl s:shard1 r:core_node5
x:addReplicaColl_shard1_replica3] o.a.s.c.RecoveryStrategy Replaying updates
buffered during PeerSync.
[junit4] 2> 1442396 INFO
(recoveryExecutor-2707-thread-1-processing-n:127.0.0.1:57014_solr
x:addReplicaColl_shard1_replica3 s:shard1 c:addReplicaColl r:core_node5)
[n:127.0.0.1:57014_solr c:addReplicaColl s:shard1 r:core_node5
x:addReplicaColl_shard1_replica3] o.a.s.c.RecoveryStrategy No replay needed.
[junit4] 2> 1442396 INFO
(recoveryExecutor-2707-thread-1-processing-n:127.0.0.1:57014_solr
x:addReplicaColl_shard1_replica3 s:shard1 c:addReplicaColl r:core_node5)
[n:127.0.0.1:57014_solr c:addReplicaColl s:shard1 r:core_node5
x:addReplicaColl_shard1_replica3] o.a.s.c.RecoveryStrategy Registering as
Active after recovery.
[junit4] 2> 1442502 INFO
(zkCallback-2715-thread-3-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl]
has occurred - updating... (live nodes size: [4])
[junit4] 2> 1442502 INFO (zkCallback-2719-thread-1-processing-n:127.0.0.
[...truncated too long message...]
2698-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.node, tag=null
[junit4] 2> 1526781 INFO (jetty-closer-2698-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1611490215
[junit4] 2> 1526781 INFO (jetty-closer-2698-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1526781 INFO (jetty-closer-2698-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1526783 INFO (jetty-closer-2698-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1526784 INFO (jetty-closer-2698-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1526785 INFO (jetty-closer-2698-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1526786 INFO (jetty-closer-2698-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1526794 INFO
(zkCallback-2718-thread-17-processing-n:127.0.0.1:42240_solr)
[n:127.0.0.1:42240_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (2)
[junit4] 2> 1526799 INFO
(zkCallback-2715-thread-7-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (2)
[junit4] 2> 1526809 WARN
(zkCallback-2718-thread-17-processing-n:127.0.0.1:42240_solr)
[n:127.0.0.1:42240_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1526812 INFO (jetty-closer-2698-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@2ce46712{/solr,null,UNAVAILABLE}
[junit4] 2> 1526817 INFO
(zkCallback-2715-thread-7-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 1526822 INFO (jetty-closer-2698-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=97652525323976712-127.0.0.1:57014_solr-n_0000000000) closing
[junit4] 2> 1526825 INFO
(OverseerStateUpdate-97652525323976712-127.0.0.1:57014_solr-n_0000000000)
[n:127.0.0.1:57014_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:57014_solr
[junit4] 2> 1528290 WARN
(zkCallback-2719-thread-13-processing-n:127.0.0.1:49359_solr)
[n:127.0.0.1:49359_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1528291 INFO (jetty-closer-2698-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1e3f4672{/solr,null,UNAVAILABLE}
[junit4] 2> 1528291 WARN
(zkCallback-2717-thread-9-processing-n:127.0.0.1:61782_solr)
[n:127.0.0.1:61782_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1528291 INFO (jetty-closer-2698-thread-4) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@45b7ad24{/solr,null,UNAVAILABLE}
[junit4] 2> 1528328 WARN
(zkCallback-2715-thread-7-processing-n:127.0.0.1:57014_solr)
[n:127.0.0.1:57014_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1528328 INFO (jetty-closer-2698-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@3ee341f8{/solr,null,UNAVAILABLE}
[junit4] 2> 1528332 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[7E8EC62C8AAAA93E]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:60030 60030
[junit4] 2> 1528333 INFO (Thread-3228) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:60030 60030
[junit4] 2> 1528336 WARN (Thread-3228) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 91 /solr/collections
[junit4] 2> 26 /solr/overseer/queue
[junit4] 2> 4 /solr/live_nodes
[junit4] 2> 3 /solr/overseer/queue-work
[junit4] 2> 2 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 1528338 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[7E8EC62C8AAAA93E]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7E8EC62C8AAAA93E-001
[junit4] 2> Mar 21, 2017 1:22:58 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=CheapBastard,
sim=RandomSimilarity(queryNorm=false): {}, locale=es-PR, timezone=Etc/GMT-11
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_121
(64-bit)/cpus=3,threads=1,free=190218096,total=528482304
[junit4] 2> NOTE: All tests run in this JVM: [TestHdfsBackupRestoreCore,
BlockDirectoryTest, DisMaxRequestHandlerTest, MultiThreadedOCPTest,
StatelessScriptUpdateProcessorFactoryTest, TestDistributedSearch,
DistributedSpellCheckComponentTest, TestManagedStopFilterFactory,
ChangedSchemaMergeTest, TestConfigSetImmutable, TestDocumentBuilder,
TestBM25SimilarityFactory, AddSchemaFieldsUpdateProcessorFactoryTest,
HighlighterTest, TestLeaderInitiatedRecoveryThread, TestCloudDeleteByQuery,
PeerSyncTest, TestCollectionAPIs, TestLeaderElectionWithEmptyReplica,
TestPerFieldSimilarity, TestSolrFieldCacheMBean,
LeaderInitiatedRecoveryOnCommitTest, OutOfBoxZkACLAndCredentialsProvidersTest,
ZkNodePropsTest, DOMUtilTest, OpenExchangeRatesOrgProviderTest,
OverseerCollectionConfigSetProcessorTest, AssignTest, TestCloudSchemaless,
DistributedQueueTest, OverseerTaskQueueTest, TestRuleBasedAuthorizationPlugin,
IndexSchemaRuntimeFieldTest, MetricUtilsTest, SortSpecParsingTest,
DistributedExpandComponentTest, HdfsCollectionsAPIDistributedZkTest,
TestSolr4Spatial2, SampleTest, TestCollationField,
TestEmbeddedSolrServerConstructors, TestDFISimilarityFactory,
CloudExitableDirectoryReaderTest, TestLFUCache, TestSortByMinMaxFunction,
TestAuthorizationFramework, DocValuesTest, ReplaceNodeTest,
TestExtendedDismaxParser, CopyFieldTest, StandardRequestHandlerTest,
SubstringBytesRefFilterTest, TestPseudoReturnFields,
TestTrackingShardHandlerFactory, RestartWhileUpdatingTest,
TestStressCloudBlindAtomicUpdates, SyncSliceTest, ZkCLITest, FileUtilsTest,
TestHighlightDedupGrouping, TestStressVersions, SolrRequestParserTest,
MigrateRouteKeyTest, SolrXmlInZkTest, TestRequestStatusCollectionAPI,
ShardSplitTest, TestManagedSchemaThreadSafety, TestManagedSchema,
ZkStateReaderTest, AnalyticsMergeStrategyTest, TestHashPartitioner,
SimpleMLTQParserTest, TestAnalyzeInfixSuggestions, CollectionStateFormat2Test,
TestSolrCoreSnapshots, TestTolerantUpdateProcessorRandomCloud,
TestSolrQueryResponse, SimpleFacetsTest, TriLevelCompositeIdRoutingTest,
OutputWriterTest, MergeStrategyTest, DistributedFacetPivotWhiteBoxTest,
MoreLikeThisHandlerTest, TestLazyCores, TestBackupRepositoryFactory,
DirectoryFactoryTest, TestSolrXml, ClassificationUpdateProcessorTest,
SchemaVersionSpecificBehaviorTest, TestRemoteStreaming,
DocValuesNotIndexedTest, ZkControllerTest, TestSizeLimitedDistributedMap,
ChaosMonkeyNothingIsSafeTest, DeleteStatusTest,
CollectionsAPIAsyncDistributedZkTest, StatsReloadRaceTest, HLLUtilTest,
TestRandomRequestDistribution, TestWordDelimiterFilterFactory,
SolrGangliaReporterTest, XsltUpdateRequestHandlerTest,
TestJavabinTupleStreamParser, UniqFieldsUpdateProcessorFactoryTest,
TestBulkSchemaAPI, TestStressLucene, TestSolrConfigHandlerCloud,
TestMergePolicyConfig, TestExclusionRuleCollectionAccess,
AnalysisAfterCoreReloadTest, SolrTestCaseJ4Test, SolrCloudReportersTest,
SolrPluginUtilsTest, TestGeoJSONResponseWriter, TestExpandComponent,
SOLR749Test, SuggesterFSTTest, TestTolerantSearch, TestSubQueryTransformer,
TestFastOutputStream, TestFieldTypeCollectionResource,
BlockJoinFacetDistribTest, TestManagedResourceStorage, SolrIndexConfigTest,
DistributedFacetPivotSmallAdvancedTest, OverseerRolesTest,
TestDynamicFieldCollectionResource, TestCSVLoader, PolyFieldTest,
SolrCoreMetricManagerTest, SystemInfoHandlerTest, SSLMigrationTest,
PluginInfoTest, TestSurroundQueryParser, TestReRankQParserPlugin,
TestOverriddenPrefixQueryForCustomFieldType, SolrIndexMetricsTest,
DocValuesMultiTest, MetricsHandlerTest, TestBlobHandler,
CoreAdminCreateDiscoverTest, TestSweetSpotSimilarityFactory,
TestRandomDVFaceting, PathHierarchyTokenizerFactoryTest, TestFieldCacheReopen,
TestXIncludeConfig, JsonValidatorTest, TestMultiWordSynonyms,
LeaderElectionTest, PrimitiveFieldTypeTest, TestCloudRecovery,
BinaryUpdateRequestHandlerTest, TermsComponentTest, TestJsonRequest,
TestStressUserVersions, SecurityConfHandlerTest, TestCSVResponseWriter,
TestSchemaManager, TestSolrCloudWithKerberosAlt, TestExactSharedStatsCache,
TestRawResponseWriter, TestSerializedLuceneMatchVersion, TimeZoneUtilsTest,
TestStressReorder, SoftAutoCommitTest,
TestPerFieldSimilarityWithDefaultOverride, TestSolrJ,
ConfigureRecoveryStrategyTest, UnloadDistributedZkTest,
CollectionsAPIDistributedZkTest]
[junit4] Completed [353/701 (1!)] on J0 in 93.89s, 20 tests, 1 failure <<<
FAILURES!
[...truncated 63831 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org
