Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.6-Linux/117/
Java: 32bit/jdk1.8.0_144 -server -XX:+UseSerialGC
1 tests failed.
FAILED:
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI
Error Message:
Something is broken in the assert for no shards using the same indexDir -
probably something was changed in the attributes published in the MBean of
SolrCore : {}
Stack Trace:
java.lang.AssertionError: Something is broken in the assert for no shards using
the same indexDir - probably something was changed in the attributes published
in the MBean of SolrCore : {}
at
__randomizedtesting.SeedInfo.seed([CD5DD475E7F09B3B:8528A0C1E1C3B4AE]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.checkNoTwoShardsUseTheSameIndexDir(CollectionsAPIDistributedZkTest.java:646)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:524)
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:748)
Build Log:
[...truncated 12149 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_CD5DD475E7F09B3B-001/init-core-data-001
[junit4] 2> 1256666 WARN
(SUITE-CollectionsAPIDistributedZkTest-seed#[CD5DD475E7F09B3B]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=34 numCloses=34
[junit4] 2> 1256666 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[CD5DD475E7F09B3B]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields
[junit4] 2> 1256667 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[CD5DD475E7F09B3B]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1256668 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[CD5DD475E7F09B3B]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_CD5DD475E7F09B3B-001/tempDir-001
[junit4] 2> 1256668 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[CD5DD475E7F09B3B]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1256668 INFO (Thread-2667) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1256668 INFO (Thread-2667) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1256669 ERROR (Thread-2667) [ ] o.a.z.s.ZooKeeperServer
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action
on ERROR or SHUTDOWN server state changes
[junit4] 2> 1256768 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[CD5DD475E7F09B3B]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:40907
[junit4] 2> 1256773 INFO (jetty-launcher-1508-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1256773 INFO (jetty-launcher-1508-thread-2) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1256787 INFO (jetty-launcher-1508-thread-3) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1256795 INFO (jetty-launcher-1508-thread-4) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1256806 INFO (jetty-launcher-1508-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4bbb9a{/solr,null,AVAILABLE}
[junit4] 2> 1256807 INFO (jetty-launcher-1508-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@1b84ac7{SSL,[ssl,
http/1.1]}{127.0.0.1:33231}
[junit4] 2> 1256807 INFO (jetty-launcher-1508-thread-2) [ ]
o.e.j.s.Server Started @1258871ms
[junit4] 2> 1256807 INFO (jetty-launcher-1508-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=33231}
[junit4] 2> 1256807 ERROR (jetty-launcher-1508-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1256807 INFO (jetty-launcher-1508-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.6.1
[junit4] 2> 1256807 INFO (jetty-launcher-1508-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1256807 INFO (jetty-launcher-1508-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1256807 INFO (jetty-launcher-1508-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-30T22:35:43.610Z
[junit4] 2> 1256809 INFO (jetty-launcher-1508-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1e6ac74{/solr,null,AVAILABLE}
[junit4] 2> 1256809 INFO (jetty-launcher-1508-thread-4) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@10a6424{SSL,[ssl,
http/1.1]}{127.0.0.1:33775}
[junit4] 2> 1256809 INFO (jetty-launcher-1508-thread-4) [ ]
o.e.j.s.Server Started @1258873ms
[junit4] 2> 1256809 INFO (jetty-launcher-1508-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=33775}
[junit4] 2> 1256810 ERROR (jetty-launcher-1508-thread-4) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1256810 INFO (jetty-launcher-1508-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.6.1
[junit4] 2> 1256810 INFO (jetty-launcher-1508-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1256810 INFO (jetty-launcher-1508-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1256810 INFO (jetty-launcher-1508-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-30T22:35:43.613Z
[junit4] 2> 1256822 INFO (jetty-launcher-1508-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1256827 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 0x15e354933f00001, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 1256837 INFO (jetty-launcher-1508-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@b452ea{/solr,null,AVAILABLE}
[junit4] 2> 1256837 INFO (jetty-launcher-1508-thread-3) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@6e9a56{SSL,[ssl,
http/1.1]}{127.0.0.1:32879}
[junit4] 2> 1256837 INFO (jetty-launcher-1508-thread-3) [ ]
o.e.j.s.Server Started @1258901ms
[junit4] 2> 1256837 INFO (jetty-launcher-1508-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=32879}
[junit4] 2> 1256837 ERROR (jetty-launcher-1508-thread-3) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1256838 INFO (jetty-launcher-1508-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.6.1
[junit4] 2> 1256838 INFO (jetty-launcher-1508-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1256838 INFO (jetty-launcher-1508-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1256838 INFO (jetty-launcher-1508-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-30T22:35:43.641Z
[junit4] 2> 1256866 INFO (jetty-launcher-1508-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@9ddce6{/solr,null,AVAILABLE}
[junit4] 2> 1256866 INFO (jetty-launcher-1508-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1256882 INFO (jetty-launcher-1508-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@1915a05{SSL,[ssl,
http/1.1]}{127.0.0.1:34369}
[junit4] 2> 1256882 INFO (jetty-launcher-1508-thread-1) [ ]
o.e.j.s.Server Started @1258946ms
[junit4] 2> 1256882 INFO (jetty-launcher-1508-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=34369}
[junit4] 2> 1256882 ERROR (jetty-launcher-1508-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1256882 INFO (jetty-launcher-1508-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.6.1
[junit4] 2> 1256882 INFO (jetty-launcher-1508-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1256882 INFO (jetty-launcher-1508-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1256882 INFO (jetty-launcher-1508-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-30T22:35:43.685Z
[junit4] 2> 1256885 INFO (jetty-launcher-1508-thread-2) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1256894 INFO (jetty-launcher-1508-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40907/solr
[junit4] 2> 1256894 INFO (jetty-launcher-1508-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1256906 INFO (jetty-launcher-1508-thread-3) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1256906 INFO (jetty-launcher-1508-thread-4) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1256909 INFO (jetty-launcher-1508-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1256914 INFO (jetty-launcher-1508-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40907/solr
[junit4] 2> 1256914 INFO (jetty-launcher-1508-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40907/solr
[junit4] 2> 1256923 INFO (jetty-launcher-1508-thread-1) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1256936 INFO (jetty-launcher-1508-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40907/solr
[junit4] 2> 1256942 INFO (jetty-launcher-1508-thread-4)
[n:127.0.0.1:33775_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1256960 INFO (jetty-launcher-1508-thread-4)
[n:127.0.0.1:33775_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:33775_solr
[junit4] 2> 1256961 INFO (jetty-launcher-1508-thread-4)
[n:127.0.0.1:33775_solr ] o.a.s.c.Overseer Overseer
(id=98574830368981001-127.0.0.1:33775_solr-n_0000000000) starting
[junit4] 2> 1256961 INFO (jetty-launcher-1508-thread-2)
[n:127.0.0.1:33231_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1256963 INFO (jetty-launcher-1508-thread-2)
[n:127.0.0.1:33231_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33231_solr
[junit4] 2> 1256990 INFO
(zkCallback-1525-thread-1-processing-n:127.0.0.1:33231_solr)
[n:127.0.0.1:33231_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1256998 INFO
(OverseerStateUpdate-98574830368981001-127.0.0.1:33775_solr-n_0000000000)
[n:127.0.0.1:33775_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1257000 INFO (jetty-launcher-1508-thread-4)
[n:127.0.0.1:33775_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33775_solr
[junit4] 2> 1257001 INFO (jetty-launcher-1508-thread-3)
[n:127.0.0.1:32879_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1257002 INFO
(zkCallback-1525-thread-1-processing-n:127.0.0.1:33231_solr)
[n:127.0.0.1:33231_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1257010 INFO
(zkCallback-1526-thread-2-processing-n:127.0.0.1:33775_solr)
[n:127.0.0.1:33775_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1257010 INFO (jetty-launcher-1508-thread-3)
[n:127.0.0.1:32879_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1257011 INFO (jetty-launcher-1508-thread-3)
[n:127.0.0.1:32879_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:32879_solr
[junit4] 2> 1257011 INFO
(zkCallback-1525-thread-1-processing-n:127.0.0.1:33231_solr)
[n:127.0.0.1:33231_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 1257046 INFO
(zkCallback-1531-thread-1-processing-n:127.0.0.1:32879_solr)
[n:127.0.0.1:32879_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1257046 INFO
(zkCallback-1531-thread-2-processing-n:127.0.0.1:32879_solr)
[n:127.0.0.1:32879_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 1257050 INFO
(zkCallback-1526-thread-1-processing-n:127.0.0.1:33775_solr)
[n:127.0.0.1:33775_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 1257052 INFO (jetty-launcher-1508-thread-1)
[n:127.0.0.1:34369_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 1257052 INFO (jetty-launcher-1508-thread-1)
[n:127.0.0.1:34369_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1257053 INFO (jetty-launcher-1508-thread-1)
[n:127.0.0.1:34369_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:34369_solr
[junit4] 2> 1257054 INFO
(zkCallback-1532-thread-1-processing-n:127.0.0.1:34369_solr)
[n:127.0.0.1:34369_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1257054 INFO
(zkCallback-1531-thread-2-processing-n:127.0.0.1:32879_solr)
[n:127.0.0.1:32879_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1257054 INFO
(zkCallback-1525-thread-1-processing-n:127.0.0.1:33231_solr)
[n:127.0.0.1:33231_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1257054 INFO
(zkCallback-1526-thread-1-processing-n:127.0.0.1:33775_solr)
[n:127.0.0.1:33775_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1257122 INFO (jetty-launcher-1508-thread-2)
[n:127.0.0.1:33231_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_CD5DD475E7F09B3B-001/tempDir-001/node2/.
[junit4] 2> 1257122 INFO (jetty-launcher-1508-thread-4)
[n:127.0.0.1:33775_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_CD5DD475E7F09B3B-001/tempDir-001/node4/.
[junit4] 2> 1257136 INFO (jetty-launcher-1508-thread-3)
[n:127.0.0.1:32879_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_CD5DD475E7F09B3B-001/tempDir-001/node3/.
[junit4] 2> 1257136 INFO (jetty-launcher-1508-thread-1)
[n:127.0.0.1:34369_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_CD5DD475E7F09B3B-001/tempDir-001/node1/.
[junit4] 2> 1257143 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[CD5DD475E7F09B3B]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1257143 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[CD5DD475E7F09B3B]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:40907/solr ready
[junit4] 2> 1257164 INFO
(TEST-CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.SolrTestCaseJ4 ###Starting deletePartiallyCreatedCollection
[junit4] 2> 1257164 INFO
(TEST-CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1257232 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr ]
o.a.s.h.a.CoreAdminOperation core create command
dataDir=/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_CD5DD475E7F09B3B-001/tempDir-002&collection.configName=conf&name=halfdeletedcollection_shard1_replica1&action=CREATE&numShards=2&collection=halfdeletedcollection&wt=javabin&version=2
[junit4] 2> 1257232 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 1257233 INFO
(OverseerStateUpdate-98574830368981001-127.0.0.1:33775_solr-n_0000000000)
[n:127.0.0.1:33775_solr ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard2
[junit4] 2> 1258241 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
[junit4] 2> 1258247 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.s.IndexSchema [halfdeletedcollection_shard1_replica1] Schema name=minimal
[junit4] 2> 1258249 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1258249 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.CoreContainer Creating SolrCore 'halfdeletedcollection_shard1_replica1'
using configuration from collection halfdeletedcollection, trusted=true
[junit4] 2> 1258250 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1258250 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SolrCore [[halfdeletedcollection_shard1_replica1] ] Opening new
SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_CD5DD475E7F09B3B-001/tempDir-001/node1/halfdeletedcollection_shard1_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_CD5DD475E7F09B3B-001/tempDir-002/]
[junit4] 2> 1258275 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1258275 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1258275 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1258275 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1258276 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@16e845a[halfdeletedcollection_shard1_replica1] main]
[junit4] 2> 1258276 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1258277 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1258277 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1258277 INFO
(searcherExecutor-3756-thread-1-processing-n:127.0.0.1:34369_solr
x:halfdeletedcollection_shard1_replica1 c:halfdeletedcollection)
[n:127.0.0.1:34369_solr c:halfdeletedcollection
x:halfdeletedcollection_shard1_replica1] o.a.s.c.SolrCore
[halfdeletedcollection_shard1_replica1] Registered new searcher
Searcher@16e845a[halfdeletedcollection_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1258277 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1577197287589806080
[junit4] 2> 1258280 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1258280 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1258280 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:34369/solr/halfdeletedcollection_shard1_replica1/
[junit4] 2> 1258280 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1258280 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SyncStrategy
https://127.0.0.1:34369/solr/halfdeletedcollection_shard1_replica1/ has no
replicas
[junit4] 2> 1258280 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1258281 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:34369/solr/halfdeletedcollection_shard1_replica1/ shard2
[junit4] 2> 1258431 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1258432 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={dataDir=/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_CD5DD475E7F09B3B-001/tempDir-002&collection.configName=conf&name=halfdeletedcollection_shard1_replica1&action=CREATE&numShards=2&collection=halfdeletedcollection&wt=javabin&version=2}
status=0 QTime=1200
[junit4] 2> 1258434 INFO (qtp23872624-9154) [n:127.0.0.1:34369_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=halfdeletedcollection&action=DELETE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 1258436 INFO
(OverseerThreadFactory-3747-thread-1-processing-n:127.0.0.1:33775_solr)
[n:127.0.0.1:33775_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 1258439 INFO (qtp23872624-9167) [n:127.0.0.1:34369_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.halfdeletedcollection.shard1.replica1
[junit4] 2> 1258439 INFO (qtp23872624-9167) [n:127.0.0.1:34369_solr ]
o.a.s.c.SolrCore [halfdeletedcollection_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@a14907
[junit4] 2> 1258440 INFO (qtp23872624-9167) [n:127.0.0.1:34369_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.halfdeletedcollection.shard1.replica1
[junit4] 2> 1258441 INFO (qtp23872624-9167) [n:127.0.0.1:34369_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={deleteInstanceDir=true&core=halfdeletedcollection_shard1_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=2
[junit4] 2> 1259145 INFO (qtp23872624-9154) [n:127.0.0.1:34369_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=halfdeletedcollection&action=DELETE&wt=javabin&version=2} status=0
QTime=710
[junit4] 2> 1259146 INFO (qtp23872624-9155) [n:127.0.0.1:34369_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1259146 INFO (qtp23872624-9155) [n:127.0.0.1:34369_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=LIST&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1259148 INFO (qtp23872624-9125) [n:127.0.0.1:34369_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf&name=halfdeletedcollection&action=CREATE&numShards=2&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1259149 INFO
(OverseerThreadFactory-3747-thread-2-processing-n:127.0.0.1:33775_solr)
[n:127.0.0.1:33775_solr ] o.a.s.c.CreateCollectionCmd Create collection
halfdeletedcollection
[junit4] 2> 1259150 INFO
(OverseerCollectionConfigSetProcessor-98574830368981001-127.0.0.1:33775_solr-n_0000000000)
[n:127.0.0.1:33775_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> 1259362 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr ]
o.a.s.u.TestInjection Inject random core creation delay of 4s
[junit4] 2> 1259428 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=halfdeletedcollection_shard2_replica1&action=CREATE&numShards=2&collection=halfdeletedcollection&shard=shard2&wt=javabin&version=2
[junit4] 2> 1259428 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 1259531 INFO
(zkCallback-1531-thread-2-processing-n:127.0.0.1:32879_solr)
[n:127.0.0.1:32879_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/halfdeletedcollection/state.json] for collection
[halfdeletedcollection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1260441 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
[junit4] 2> 1260475 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.s.IndexSchema [halfdeletedcollection_shard2_replica1] Schema name=minimal
[junit4] 2> 1260478 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1260478 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.CoreContainer Creating SolrCore 'halfdeletedcollection_shard2_replica1'
using configuration from collection halfdeletedcollection, trusted=true
[junit4] 2> 1260478 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1260478 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.SolrCore [[halfdeletedcollection_shard2_replica1] ] Opening new
SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_CD5DD475E7F09B3B-001/tempDir-001/node3/halfdeletedcollection_shard2_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_CD5DD475E7F09B3B-001/tempDir-001/node3/./halfdeletedcollection_shard2_replica1/data/]
[junit4] 2> 1260516 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1260516 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1260517 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1260517 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1260517 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1bbe8ef[halfdeletedcollection_shard2_replica1] main]
[junit4] 2> 1260518 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1260518 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1260518 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1260518 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1577197289939664896
[junit4] 2> 1260518 INFO
(searcherExecutor-3761-thread-1-processing-n:127.0.0.1:32879_solr
x:halfdeletedcollection_shard2_replica1 s:shard2 c:halfdeletedcollection)
[n:127.0.0.1:32879_solr c:halfdeletedcollection s:shard2
x:halfdeletedcollection_shard2_replica1] o.a.s.c.SolrCore
[halfdeletedcollection_shard2_replica1] Registered new searcher
Searcher@1bbe8ef[halfdeletedcollection_shard2_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1260521 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1260521 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1260521 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:32879/solr/halfdeletedcollection_shard2_replica1/
[junit4] 2> 1260521 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1260521 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.SyncStrategy
https://127.0.0.1:32879/solr/halfdeletedcollection_shard2_replica1/ has no
replicas
[junit4] 2> 1260521 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1260522 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:32879/solr/halfdeletedcollection_shard2_replica1/ shard2
[junit4] 2> 1260623 INFO
(zkCallback-1531-thread-2-processing-n:127.0.0.1:32879_solr)
[n:127.0.0.1:32879_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/halfdeletedcollection/state.json] for collection
[halfdeletedcollection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1260673 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1260674 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=halfdeletedcollection_shard2_replica1&action=CREATE&numShards=2&collection=halfdeletedcollection&shard=shard2&wt=javabin&version=2}
status=0 QTime=1246
[junit4] 2> 1260775 INFO
(zkCallback-1531-thread-2-processing-n:127.0.0.1:32879_solr)
[n:127.0.0.1:32879_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/halfdeletedcollection/state.json] for collection
[halfdeletedcollection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1263362 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=halfdeletedcollection_shard1_replica1&action=CREATE&numShards=2&collection=halfdeletedcollection&shard=shard1&wt=javabin&version=2
[junit4] 2> 1263366 INFO
(zkCallback-1531-thread-2-processing-n:127.0.0.1:32879_solr)
[n:127.0.0.1:32879_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/halfdeletedcollection/state.json] for collection
[halfdeletedcollection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1263366 INFO
(zkCallback-1532-thread-1-processing-n:127.0.0.1:34369_solr)
[n:127.0.0.1:34369_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/halfdeletedcollection/state.json] for collection
[halfdeletedcollection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1264370 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
[junit4] 2> 1264373 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.s.IndexSchema [halfdeletedcollection_shard1_replica1] Schema name=minimal
[junit4] 2> 1264374 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1264374 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.CoreContainer Creating SolrCore 'halfdeletedcollection_shard1_replica1'
using configuration from collection halfdeletedcollection, trusted=true
[junit4] 2> 1264375 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1264375 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SolrCore [[halfdeletedcollection_shard1_replica1] ] Opening new
SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_CD5DD475E7F09B3B-001/tempDir-001/node1/halfdeletedcollection_shard1_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_CD5DD475E7F09B3B-001/tempDir-001/node1/./halfdeletedcollection_shard1_replica1/data/]
[junit4] 2> 1264400 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1264400 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1264400 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1264400 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1264401 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1b574d4[halfdeletedcollection_shard1_replica1] main]
[junit4] 2> 1264401 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1264402 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1264402 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1264402 INFO
(searcherExecutor-3766-thread-1-processing-n:127.0.0.1:34369_solr
x:halfdeletedcollection_shard1_replica1 s:shard1 c:halfdeletedcollection)
[n:127.0.0.1:34369_solr c:halfdeletedcollection s:shard1
x:halfdeletedcollection_shard1_replica1] o.a.s.c.SolrCore
[halfdeletedcollection_shard1_replica1] Registered new searcher
Searcher@1b574d4[halfdeletedcollection_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1264402 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1577197294012334080
[junit4] 2> 1264405 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1264405 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1264405 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:34369/solr/halfdeletedcollection_shard1_replica1/
[junit4] 2> 1264405 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1264405 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SyncStrategy
https://127.0.0.1:34369/solr/halfdeletedcollection_shard1_replica1/ has no
replicas
[junit4] 2> 1264405 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1264405 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:34369/solr/halfdeletedcollection_shard1_replica1/ shard1
[junit4] 2> 1264507 INFO
(zkCallback-1532-thread-1-processing-n:127.0.0.1:34369_solr)
[n:127.0.0.1:34369_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/halfdeletedcollection/state.json] for collection
[halfdeletedcollection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1264507 INFO
(zkCallback-1531-thread-2-processing-n:127.0.0.1:32879_solr)
[n:127.0.0.1:32879_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/halfdeletedcollection/state.json] for collection
[halfdeletedcollection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1264556 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1264557 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=halfdeletedcollection_shard1_replica1&action=CREATE&numShards=2&collection=halfdeletedcollection&shard=shard1&wt=javabin&version=2}
status=0 QTime=5194
[junit4] 2> 1264558 INFO (qtp23872624-9125) [n:127.0.0.1:34369_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> 1264658 INFO
(zkCallback-1531-thread-2-processing-n:127.0.0.1:32879_solr)
[n:127.0.0.1:32879_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/halfdeletedcollection/state.json] for collection
[halfdeletedcollection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1264658 INFO
(zkCallback-1532-thread-1-processing-n:127.0.0.1:34369_solr)
[n:127.0.0.1:34369_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/halfdeletedcollection/state.json] for collection
[halfdeletedcollection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1265151 INFO
(OverseerCollectionConfigSetProcessor-98574830368981001-127.0.0.1:33775_solr-n_0000000000)
[n:127.0.0.1:33775_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> 1265558 INFO (qtp23872624-9125) [n:127.0.0.1:34369_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf&name=halfdeletedcollection&action=CREATE&numShards=2&wt=javabin&version=2}
status=0 QTime=6410
[junit4] 2> 1265560 INFO (qtp23872624-9167) [n:127.0.0.1:34369_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1265560 INFO (qtp23872624-9167) [n:127.0.0.1:34369_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=LIST&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1265560 INFO
(TEST-CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.SolrTestCaseJ4 ###Ending deletePartiallyCreatedCollection
[junit4] 2> 1265602 INFO
(TEST-CollectionsAPIDistributedZkTest.testBadActionNames-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testBadActionNames
[junit4] 2> 1265603 INFO
(TEST-CollectionsAPIDistributedZkTest.testBadActionNames-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1265605 INFO (qtp23872624-9155) [n:127.0.0.1:34369_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=halfdeletedcollection&action=DELETE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 1265608 INFO
(OverseerThreadFactory-3747-thread-3-processing-n:127.0.0.1:33775_solr)
[n:127.0.0.1:33775_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 1265611 INFO (qtp23872624-9154) [n:127.0.0.1:34369_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.halfdeletedcollection.shard1.replica1
[junit4] 2> 1265611 INFO (qtp23872624-9154) [n:127.0.0.1:34369_solr ]
o.a.s.c.SolrCore [halfdeletedcollection_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@16dc004
[junit4] 2> 1265611 INFO (qtp30735704-9158) [n:127.0.0.1:32879_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.halfdeletedcollection.shard2.replica1
[junit4] 2> 1265612 INFO (qtp30735704-9158) [n:127.0.0.1:32879_solr ]
o.a.s.c.SolrCore [halfdeletedcollection_shard2_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@17578af
[junit4] 2> 1265614 INFO (qtp23872624-9154) [n:127.0.0.1:34369_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.halfdeletedcollection.shard1.replica1
[junit4] 2> 1265614 INFO (qtp30735704-9158) [n:127.0.0.1:32879_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.halfdeletedcollection.shard2.replica1
[junit4] 2> 1265616 INFO (qtp23872624-9154) [n:127.0.0.1:34369_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={deleteInstanceDir=true&core=halfdeletedcollection_shard1_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=5
[junit4] 2> 1265618 INFO (qtp30735704-9158) [n:127.0.0.1:32879_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={deleteInstanceDir=true&core=halfdeletedcollection_shard2_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=7
[junit4] 2> 1266322 INFO (qtp23872624-9155) [n:127.0.0.1:34369_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=halfdeletedcollection&action=DELETE&wt=javabin&version=2} status=0
QTime=716
[junit4] 2> 1266325 ERROR (qtp23872624-9122) [n:127.0.0.1:34369_solr ]
o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: Unknown
action: BADACTION
[junit4] 2> at
org.apache.solr.handler.admin.CollectionsHandler.handleRequestBody(CollectionsHandler.java:210)
[junit4] 2> at
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:173)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:748)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:729)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:510)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:361)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:305)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at
org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:202)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1266326 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=badactioncollection&action=BADACTION&numShards=2&wt=javabin&version=2}
status=400 QTime=0
[junit4] 2> 1266326 INFO
(TEST-CollectionsAPIDistributedZkTest.testBadActionNames-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testBadActionNames
[junit4] 2> 1266372 INFO
(TEST-CollectionsAPIDistributedZkTest.testMaxNodesPerShard-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testMaxNodesPerShard
[junit4] 2> 1266373 INFO
(TEST-CollectionsAPIDistributedZkTest.testMaxNodesPerShard-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1266379 INFO (qtp23872624-9167) [n:127.0.0.1:34369_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=2&collection.configName=conf&name=oversharded&action=CREATE&numShards=3&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1266380 INFO
(OverseerThreadFactory-3747-thread-4-processing-n:127.0.0.1:33775_solr)
[n:127.0.0.1:33775_solr ] o.a.s.c.CreateCollectionCmd Create collection
oversharded
[junit4] 2> 1266381 INFO
(OverseerCollectionConfigSetProcessor-98574830368981001-127.0.0.1:33775_solr-n_0000000000)
[n:127.0.0.1:33775_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> 1266381 ERROR
(OverseerThreadFactory-3747-thread-4-processing-n:127.0.0.1:33775_solr)
[n:127.0.0.1:33775_solr ] o.a.s.c.OverseerCollectionMessageHandler
Collection: oversharded operation: create
failed:org.apache.solr.common.SolrException: Cannot create collection
oversharded. Value of maxShardsPerNode is 1, and the number of nodes currently
live or live and part of your createNodeSet is 4. This allows a maximum of 4 to
be created. Value of numShards is 3 and value of replicationFactor is 2. This
requires 6 shards to be created (higher than the allowed number)
[junit4] 2> at
org.apache.solr.cloud.CreateCollectionCmd.call(CreateCollectionCmd.java:155)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:226)
[junit4] 2> at
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:464)
[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:1149)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1266394 INFO (qtp23872624-9167) [n:127.0.0.1:34369_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=2&collection.configName=conf&name=oversharded&action=CREATE&numShards=3&wt=javabin&version=2}
status=400 QTime=15
[junit4] 2> 1266394 INFO
(TEST-CollectionsAPIDistributedZkTest.testMaxNodesPerShard-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testMaxNodesPerShard
[junit4] 2> 1266420 INFO
(TEST-CollectionsAPIDistributedZkTest.testCreationAndDeletion-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testCreationAndDeletion
[junit4] 2> 1266421 INFO
(TEST-CollectionsAPIDistributedZkTest.testCreationAndDeletion-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1266423 INFO (qtp23872624-9154) [n:127.0.0.1:34369_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf&name=created_and_deleted&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1266424 INFO
(OverseerThreadFactory-3747-thread-5-processing-n:127.0.0.1:33775_solr)
[n:127.0.0.1:33775_solr ] o.a.s.c.CreateCollectionCmd Create collection
created_and_deleted
[junit4] 2> 1266424 INFO
(OverseerCollectionConfigSetProcessor-98574830368981001-127.0.0.1:33775_solr-n_0000000000)
[n:127.0.0.1:33775_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> 1266688 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=created_and_deleted_shard1_replica1&action=CREATE&numShards=1&collection=created_and_deleted&shard=shard1&wt=javabin&version=2
[junit4] 2> 1266688 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 1266791 INFO
(zkCallback-1526-thread-1-processing-n:127.0.0.1:33775_solr)
[n:127.0.0.1:33775_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/created_and_deleted/state.json] for collection
[created_and_deleted] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1267700 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
[junit4] 2> 1267736 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.s.IndexSchema [created_and_deleted_shard1_replica1] Schema name=minimal
[junit4] 2> 1267738 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1267738 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.c.CoreContainer Creating SolrCore 'created_and_deleted_shard1_replica1'
using configuration from collection created_and_deleted, trusted=true
[junit4] 2> 1267739 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1267739 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.c.SolrCore [[created_and_deleted_shard1_replica1] ] Opening new SolrCore
at
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_CD5DD475E7F09B3B-001/tempDir-001/node4/created_and_deleted_shard1_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_CD5DD475E7F09B3B-001/tempDir-001/node4/./created_and_deleted_shard1_replica1/data/]
[junit4] 2> 1267771 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1267771 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1267771 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1267771 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1267772 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@190512f[created_and_deleted_shard1_replica1] main]
[junit4] 2> 1267773 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1267773 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1267773 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1267773 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1577197297547083776
[junit4] 2> 1267773 INFO
(searcherExecutor-3771-thread-1-processing-n:127.0.0.1:33775_solr
x:created_and_deleted_shard1_replica1 s:shard1 c:created_and_deleted)
[n:127.0.0.1:33775_solr c:created_and_deleted s:shard1
x:created_and_deleted_shard1_replica1] o.a.s.c.SolrCore
[created_and_deleted_shard1_replica1] Registered new searcher
Searcher@190512f[created_and_deleted_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1267776 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1267776 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1267776 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:33775/solr/created_and_deleted_shard1_replica1/
[junit4] 2> 1267776 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1267776 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.c.SyncStrategy
https://127.0.0.1:33775/solr/created_and_deleted_shard1_replica1/ has no
replicas
[junit4] 2> 1267776 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1267777 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:33775/solr/created_and_deleted_shard1_replica1/ shard1
[junit4] 2> 1267878 INFO
(zkCallback-1526-thread-3-processing-n:127.0.0.1:33775_solr)
[n:127.0.0.1:33775_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/created_and_deleted/state.json] for collection
[created_and_deleted] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1267927 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1267928 INFO (qtp32802825-9140) [n:127.0.0.1:33775_solr
c:created_and_deleted s:shard1 x:created_and_deleted_shard1_replica1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=created_and_deleted_shard1_replica1&action=CREATE&numShards=1&collection=created_and_deleted&shard=shard1&wt=javabin&version=2}
status=0 QTime=1241
[junit4] 2> 1267930 INFO (qtp23872624-9154) [n:127.0.0.1:34369_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> 1268031 INFO
(zkCallback-1526-thread-3-processing-n:127.0.0.1:33775_solr)
[n:127.0.0.1:33775_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/created_and_deleted/state.json] for collection
[created_and_deleted] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1268425 INFO
(OverseerCollectionConfigSetProcessor-98574830368981001-127.0.0.1:33775_solr-n_0000000000)
[n:127.0.0.1:33775_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000008 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1268931 INFO (qtp23872624-9154) [n:127.0.0.1:34369_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf&name=created_and_deleted&action=CREATE&numShards=1&wt=javabin&version=2}
status=0 QTime=2508
[junit4] 2> 1268933 INFO (qtp23872624-9155) [n:127.0.0.1:34369_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1268934 INFO (qtp23872624-9155) [n:127.0.0.1:34369_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=LIST&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1268936 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=created_and_deleted&action=DELETE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 1268938 INFO
(OverseerThreadFactory-3747-thread-5-processing-n:127.0.0.1:33775_solr)
[n:127.0.0.1:33775_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 1268941 INFO (qtp32802825-9145) [n:127.0.0.1:33775_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.created_and_deleted.shard1.replica1
[junit4] 2> 1268941 INFO (qtp32802825-9145) [n:127.0.0.1:33775_solr ]
o.a.s.c.SolrCore [created_and_deleted_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@1f66af4
[junit4] 2> 1268942 INFO (qtp32802825-9145) [n:127.0.0.1:33775_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.created_and_deleted.shard1.replica1
[junit4] 2> 1268946 INFO (qtp32802825-9145) [n:127.0.0.1:33775_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={deleteInstanceDir=true&core=created_and_deleted_shard1_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=5
[junit4] 2> 1269650 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=created_and_deleted&action=DELETE&wt=javabin&version=2} status=0
QTime=714
[junit4] 2> 1269652 INFO (qtp23872624-9167) [n:127.0.0.1:34369_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1269652 INFO (qtp23872624-9167) [n:127.0.0.1:34369_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=LIST&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1269653 INFO
(TEST-CollectionsAPIDistributedZkTest.testCreationAndDeletion-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testCreationAndDeletion
[junit4] 2> 1269699 INFO
(TEST-CollectionsAPIDistributedZkTest.testCreateNodeSet-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateNodeSet
[junit4] 2> 1269700 INFO
(TEST-CollectionsAPIDistributedZkTest.testCreateNodeSet-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1269703 INFO (qtp23872624-9154) [n:127.0.0.1:34369_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf&name=nodeset_collection&action=CREATE&numShards=2&createNodeSet=https://127.0.0.1:33231/solr,https://127.0.0.1:34369/solr&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1269705 INFO
(OverseerThreadFactory-3747-thread-5-processing-n:127.0.0.1:33775_solr)
[n:127.0.0.1:33775_solr ] o.a.s.c.CreateCollectionCmd Create collection
nodeset_collection
[junit4] 2> 1269705 INFO
(OverseerCollectionConfigSetProcessor-98574830368981001-127.0.0.1:33775_solr-n_0000000000)
[n:127.0.0.1:33775_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000010 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1269705 WARN
(OverseerThreadFactory-3747-thread-5-processing-n:127.0.0.1:33775_solr)
[n:127.0.0.1:33775_solr ] o.a.s.c.CreateCollectionCmd It is unusual to
create a collection (nodeset_collection) without cores.
[junit4] 2> 1269910 INFO (qtp23872624-9154) [n:127.0.0.1:34369_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> 1269910 INFO (qtp23872624-9154) [n:127.0.0.1:34369_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf&name=nodeset_collection&action=CREATE&numShards=2&createNodeSet=https://127.0.0.1:33231/solr,https://127.0.0.1:34369/solr&wt=javabin&version=2}
status=0 QTime=206
[junit4] 2> 1269910 INFO
(TEST-CollectionsAPIDistributedZkTest.testCreateNodeSet-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateNodeSet
[junit4] 2> 1269943 INFO
(TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testTooManyReplicas
[junit4] 2> 1269944 INFO
(TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1269946 INFO (qtp23872624-9155) [n:127.0.0.1:34369_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=nodeset_collection&action=DELETE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 1269947 INFO
(OverseerCollectionConfigSetProcessor-98574830368981001-127.0.0.1:33775_solr-n_0000000000)
[n:127.0.0.1:33775_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000012 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1269947 INFO
(OverseerThreadFactory-3747-thread-5-processing-n:127.0.0.1:33775_solr)
[n:127.0.0.1:33775_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 1270650 INFO (qtp23872624-9155) [n:127.0.0.1:34369_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=nodeset_collection&action=DELETE&wt=javabin&version=2} status=0
QTime=704
[junit4] 2> 1270653 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=10&collection.configName=conf&name=collection&action=CREATE&numShards=2&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1270655 INFO
(OverseerThreadFactory-3747-thread-5-processing-n:127.0.0.1:33775_solr)
[n:127.0.0.1:33775_solr ] o.a.s.c.CreateCollectionCmd Create collection
collection
[junit4] 2> 1270655 WARN
(OverseerThreadFactory-3747-thread-5-processing-n:127.0.0.1:33775_solr)
[n:127.0.0.1:33775_solr ] o.a.s.c.CreateCollectionCmd Specified
replicationFactor of 10 on collection collection is higher than or equal to the
number of Solr instances currently live or live and part of your
createNodeSet(4). It's unusual to run two replica of the same slice on the same
Solr-instance.
[junit4] 2> 1270655 INFO
(OverseerCollectionConfigSetProcessor-98574830368981001-127.0.0.1:33775_solr-n_0000000000)
[n:127.0.0.1:33775_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000014 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1270655 ERROR
(OverseerThreadFactory-3747-thread-5-processing-n:127.0.0.1:33775_solr)
[n:127.0.0.1:33775_solr ] o.a.s.c.OverseerCollectionMessageHandler
Collection: collection operation: create
failed:org.apache.solr.common.SolrException: Cannot create collection
collection. Value of maxShardsPerNode is 1, and the number of nodes currently
live or live and part of your createNodeSet is 4. This allows a maximum of 4 to
be created. Value of numShards is 2 and value of replicationFactor is 10. This
requires 20 shards to be created (higher than the allowed number)
[junit4] 2> at
org.apache.solr.cloud.CreateCollectionCmd.call(CreateCollectionCmd.java:155)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:226)
[junit4] 2> at
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:464)
[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:1149)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1270658 INFO (qtp23872624-9122) [n:127.0.0.1:34369_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=10&collection.configName=conf&name=collection&action=CREATE&numShards=2&wt=javabin&version=2}
status=400 QTime=5
[junit4] 2> 1270659 INFO
(TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testTooManyReplicas
[junit4] 2> 1270708 INFO
(TEST-CollectionsAPIDistributedZkTest.testMissingRequiredParameters-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testMissingRequiredParameters
[junit4] 2> 1270709 INFO
(TEST-CollectionsAPIDistributedZkTest.testMissingRequiredParameters-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1270712 INFO (qtp23872624-9167) [n:127.0.0.1:34369_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1270713 ERROR (qtp23872624-9167) [n:127.0.0.1:34369_solr ]
o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: Missing
required parameter: name
[junit4] 2> at
org.apache.solr.common.params.RequiredSolrParams.getParams(RequiredSolrParams.java:90)
[junit4] 2> at
org.apache.solr.common.params.SolrParams.getAll(SolrParams.java:462)
[junit4] 2> at
org.apache.solr.common.params.SolrParams.getAll(SolrParams.java:479)
[junit4] 2> at
org.apache.solr.handler.admin.CollectionsHandler$CollectionOperation.lambda$static$0(CollectionsHandler.java:396)
[junit4] 2> at
org.apache.solr.handler.admin.CollectionsHandler$CollectionOperation.execute(CollectionsHandler.java:922)
[junit4] 2> at
org.apache.solr.handler.admin.CollectionsHandler.invokeAction(CollectionsHandler.java:228)
[junit4] 2> at
org.apache.solr.handler.admin.CollectionsHandler.handleRequestBody(CollectionsHandler.java:215)
[junit4] 2> at
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:173)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:748)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:729)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:510)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:361)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:305)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at
org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:202)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1270713 INFO (qtp23872624-9167) [n:127.0.0.1:34369_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=CREATE&numShards=2&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 1270714 INFO
(TEST-CollectionsAPIDistributedZkTest.testMissingRequiredParameters-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testMissingRequiredParameters
[junit4] 2> 1270759 INFO
(TEST-CollectionsAPIDistributedZkTest.testCoresAreDistributedAcrossNodes-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testCoresAreDistributedAcrossNodes
[junit4] 2> 1270760 INFO
(TEST-CollectionsAPIDistributedZkTest.testCoresAreDistributedAcrossNodes-seed#[CD5DD475E7F09B3B])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1270763 INFO (qtp23872624-9154) [n:127.0.0.1:34369_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=2&collection.configName=conf&name=nodes_used_collection&action=CREATE&numShards=2&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1270765 INFO
(OverseerThreadFactory-3747-thread-5-processing-n:127.0.0.1:33775_solr)
[n:127.0.0.1:33775_solr ] o.a.s.c.CreateCollectionCmd Create collection
nodes_used_collection
[junit4] 2> 1270765 INFO
(OverseerCollectionConfigSetProcessor-98574830368981001-127.0.0.1:33775_solr-n_0000000000)
[n:127.0.0.1:33775_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000016 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1270974 INFO (qtp23872624-9125) [n:127.0.0.1:34369_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=nodes_used_collection_shard1_replica2&action=CREATE&numShards=2&collection=nodes_used_collection&shard=shard1&wt=javabin&version=2
[junit4] 2> 1270974 INFO (qtp32802825-9146) [n:127.0.0.1:33775_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=nodes_used_collection_shard1_replica1&action=CREATE&numShards=2&collection=nodes_used_collection&shard=shard1&wt=javabin&version=2
[junit4] 2> 1270988 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr ]
o.a.s.u.TestInjection Inject random core creation delay of 4s
[junit4] 2> 1271055 INFO (qtp10547940-9123) [n:127.0.0.1:33231_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=nodes_used_collection_shard2_replica1&action=CREATE&numShards=2&collection=nodes_used_collection&shard=shard2&wt=javabin&version=2
[junit4] 2> 1271056 INFO (qtp10547940-9123) [n:127.0.0.1:33231_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 1271158 INFO
(zkCallback-1532-thread-1-processing-n:127.0.0.1:34369_solr)
[n:127.0.0.1:34369_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/nodes_used_collection/state.json] for collection
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1271158 INFO
(zkCallback-1525-thread-1-processing-n:127.0.0.1:33231_solr)
[n:127.0.0.1:33231_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/nodes_used_collection/state.json] for collection
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1271158 INFO
(zkCallback-1526-thread-3-processing-n:127.0.0.1:33775_solr)
[n:127.0.0.1:33775_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/nodes_used_collection/state.json] for collection
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1271984 INFO (qtp32802825-9146) [n:127.0.0.1:33775_solr
c:nodes_used_collection s:shard1 x:nodes_used_collection_shard1_replica1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
[junit4] 2> 1271987 INFO (qtp23872624-9125) [n:127.0.0.1:34369_solr
c:nodes_used_collection s:shard1 x:nodes_used_collection_shard1_replica2]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
[junit4] 2> 1271989 INFO (qtp32802825-9146) [n:127.0.0.1:33775_solr
c:nodes_used_collection s:shard1 x:nodes_used_collection_shard1_replica1]
o.a.s.s.IndexSchema [nodes_used_collection_shard1_replica1] Schema name=minimal
[junit4] 2> 1271991 INFO (qtp32802825-9146) [n:127.0.0.1:33775_solr
c:nodes_used_collection s:shard1 x:nodes_used_collection_shard1_replica1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1271991 INFO (qtp32802825-9146) [n:127.0.0.1:33775_solr
c:nodes_used_collection s:shard1 x:nodes_used_collection_shard1_replica1]
o.a.s.c.CoreContainer Creating SolrCore 'nodes_used_collection_shard1_replica1'
using configuration from collection nodes_used_collection, trusted=true
[junit4] 2> 1271992 INFO (qtp32802825-9146) [n:127.0.0.1:33775_solr
c:nodes_used_collection s:shard1 x:nodes_used_collection_shard1_replica1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1271992 INFO (qtp32802825-9146) [n:127.0.0.1:33775_solr
c:nodes_
[...truncated too long message...]
handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:174)
[junit4] 2> at
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:173)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:748)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:729)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:510)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:361)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:305)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at
org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:202)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1387751 INFO (qtp30735704-9150) [n:127.0.0.1:32879_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:33775_solr&onlyIfLeaderActive=true&core=addReplicaColl_shard1_replica2&coreNodeName=core_node7&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=400 QTime=24008
[junit4] 2> 1388897 WARN
(zkCallback-1532-thread-4-processing-n:127.0.0.1:34369_solr)
[n:127.0.0.1:34369_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1388897 WARN
(zkCallback-1531-thread-5-processing-n:127.0.0.1:32879_solr)
[n:127.0.0.1:32879_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1388897 WARN
(zkCallback-1525-thread-2-processing-n:127.0.0.1:33231_solr)
[n:127.0.0.1:33231_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1388897 INFO (jetty-closer-1509-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@b452ea{/solr,null,UNAVAILABLE}
[junit4] 2> 1388898 INFO (jetty-closer-1509-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@4bbb9a{/solr,null,UNAVAILABLE}
[junit4] 2> 1388898 INFO (jetty-closer-1509-thread-4) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@9ddce6{/solr,null,UNAVAILABLE}
[junit4] 2> 1388898 ERROR
(SUITE-CollectionsAPIDistributedZkTest-seed#[CD5DD475E7F09B3B]-worker) [ ]
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper
server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 1388898 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[CD5DD475E7F09B3B]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:40907 40907
[junit4] 2> 1388899 INFO (Thread-2715) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:40907 40907
[junit4] 2> 1388900 WARN (Thread-2715) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 61 /solr/collections
[junit4] 2>
[junit4] 2> 1388900 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[CD5DD475E7F09B3B]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_CD5DD475E7F09B3B-001
[junit4] 2> Aug 30, 2017 10:37:55 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62):
{_version_=PostingsFormat(name=MockRandom), id=PostingsFormat(name=Memory
doPackFST= false)}, docValues:{}, maxPointsInLeafNode=1776,
maxMBSortInHeap=5.1168739127379625,
sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=ro,
timezone=Pacific/Auckland
[junit4] 2> NOTE: Linux 4.10.0-33-generic i386/Oracle Corporation
1.8.0_144 (32-bit)/cpus=8,threads=1,free=81764880,total=363859968
[junit4] 2> NOTE: All tests run in this JVM: [TestReloadDeadlock,
RestartWhileUpdatingTest, TestEmbeddedSolrServerConstructors,
TestSchemaManager, TestTrackingShardHandlerFactory, SearchHandlerTest,
ChaosMonkeySafeLeaderTest, PKIAuthenticationIntegrationTest,
TestFileDictionaryLookup, HdfsDirectoryFactoryTest, SystemInfoHandlerTest,
TestSolrCloudWithDelegationTokens, CursorMarkTest, SparseHLLTest,
BlockCacheTest, TestRTGBase, TestSubQueryTransformerDistrib,
DistributedMLTComponentTest, MultiTermTest, TestFiltering,
TestChildDocTransformer, TestQueryUtils, MoveReplicaTest, TestFieldCacheSort,
OverseerTaskQueueTest, DistributedQueryComponentOptimizationTest,
TestSolrIndexConfig, CloneFieldUpdateProcessorFactoryTest, ZkControllerTest,
TestMiniSolrCloudClusterSSL, TestLuceneMatchVersion, TestJsonFacetRefinement,
ZkStateReaderTest, TestFieldCacheWithThreads, SolrMetricsIntegrationTest,
TestConfigReload, TestCharFilters, TestStressVersions,
TestWordDelimiterFilterFactory, SchemaVersionSpecificBehaviorTest,
GraphQueryTest, DistribCursorPagingTest, SampleTest,
DistributedFacetPivotSmallTest, SolrJmxReporterCloudTest, MetricsHandlerTest,
TestStressCloudBlindAtomicUpdates, CloudMLTQParserTest,
HdfsCollectionsAPIDistributedZkTest, TestUnifiedSolrHighlighter,
AddSchemaFieldsUpdateProcessorFactoryTest, TestImpersonationWithHadoopAuth,
TestSearchPerf, CopyFieldTest, HttpSolrCallGetCoreTest, SolrIndexConfigTest,
ConvertedLegacyTest, CdcrRequestHandlerTest, TestSolrFieldCacheMBean,
TestSQLHandlerNonCloud, DateMathParserTest, CollectionStateFormat2Test,
TestPerFieldSimilarityWithDefaultOverride, TestOrdValues,
TestPartialUpdateDeduplication, TestRuleBasedAuthorizationPlugin,
TestObjectReleaseTracker, URLClassifyProcessorTest, TestCustomSort,
DocValuesTest, SortByFunctionTest, RankQueryTest, TestDFISimilarityFactory,
LoggingHandlerTest, SoftAutoCommitTest, TestSimpleTrackingShardHandler,
TestSchemaSimilarityResource, TestFastOutputStream, TestFieldTypeResource,
EchoParamsTest, ShardRoutingCustomTest, TestManagedStopFilterFactory,
TestPayloadScoreQParserPlugin, IndexSchemaRuntimeFieldTest,
BlockJoinFacetDistribTest, TestHashPartitioner, SpatialHeatmapFacetsTest,
UpdateRequestProcessorFactoryTest, TestElisionMultitermQuery, TestRestManager,
TestSolrQueryParserDefaultOperatorResource, HdfsWriteToMultipleCollectionsTest,
TestFieldCache, TestCollationFieldDocValues, SegmentsInfoRequestHandlerTest,
JSONWriterTest, SolrCloudExampleTest, UUIDUpdateProcessorFallbackTest,
BasicFunctionalityTest, TestTolerantUpdateProcessorCloud,
ClassificationUpdateProcessorFactoryTest, TestCloudManagedSchema,
TestQuerySenderListener, DirectSolrConnectionTest, TestConfigOverlay,
OpenCloseCoreStressTest, CoreSorterTest, DistributedIntervalFacetingTest,
IndexSchemaTest, TestDynamicLoading, SpellCheckComponentTest,
PeerSyncWithIndexFingerprintCachingTest,
OutOfBoxZkACLAndCredentialsProvidersTest, TriLevelCompositeIdRoutingTest,
TestSolrCoreSnapshots, ClusterStateUpdateTest, TestNoOpRegenerator,
UnloadDistributedZkTest, CollectionsAPIDistributedZkTest]
[junit4] Completed [358/713 (1!)] on J1 in 132.75s, 20 tests, 1 failure <<<
FAILURES!
[...truncated 40641 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
