Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.6-Linux/126/
Java: 64bit/jdk1.8.0_144 -XX:-UseCompressedOops -XX:+UseParallelGC
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([3BAACB4C89E0B94E:73DFBFF88FD396DB]: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 11691 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_3BAACB4C89E0B94E-001/init-core-data-001
[junit4] 2> 535274 WARN
(SUITE-CollectionsAPIDistributedZkTest-seed#[3BAACB4C89E0B94E]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 535274 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[3BAACB4C89E0B94E]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields
[junit4] 2> 535274 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[3BAACB4C89E0B94E]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 535275 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[3BAACB4C89E0B94E]-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_3BAACB4C89E0B94E-001/tempDir-001
[junit4] 2> 535275 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[3BAACB4C89E0B94E]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 535275 INFO (Thread-944) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 535275 INFO (Thread-944) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 535277 ERROR (Thread-944) [ ] 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> 535375 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[3BAACB4C89E0B94E]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:34785
[junit4] 2> 535380 INFO (jetty-launcher-578-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 535380 INFO (jetty-launcher-578-thread-3) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 535380 INFO (jetty-launcher-578-thread-2) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 535382 INFO (jetty-launcher-578-thread-4) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 535383 INFO (jetty-launcher-578-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@42cdfd6f{/solr,null,AVAILABLE}
[junit4] 2> 535385 INFO (jetty-launcher-578-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@75a63c62{/solr,null,AVAILABLE}
[junit4] 2> 535386 INFO (jetty-launcher-578-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@b7f7378{/solr,null,AVAILABLE}
[junit4] 2> 535387 INFO (jetty-launcher-578-thread-4) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@1e4b42e2{HTTP/1.1,[http/1.1]}{127.0.0.1:39241}
[junit4] 2> 535387 INFO (jetty-launcher-578-thread-4) [ ]
o.e.j.s.Server Started @537461ms
[junit4] 2> 535387 INFO (jetty-launcher-578-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=39241}
[junit4] 2> 535387 ERROR (jetty-launcher-578-thread-4) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 535387 INFO (jetty-launcher-578-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.6.1
[junit4] 2> 535387 INFO (jetty-launcher-578-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 535387 INFO (jetty-launcher-578-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 535387 INFO (jetty-launcher-578-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-09-01T22:44:32.394Z
[junit4] 2> 535390 INFO (jetty-launcher-578-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4114d789{/solr,null,AVAILABLE}
[junit4] 2> 535390 INFO (jetty-launcher-578-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@6af18f0c{HTTP/1.1,[http/1.1]}{127.0.0.1:36239}
[junit4] 2> 535390 INFO (jetty-launcher-578-thread-2) [ ]
o.e.j.s.Server Started @537465ms
[junit4] 2> 535390 INFO (jetty-launcher-578-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=36239}
[junit4] 2> 535391 ERROR (jetty-launcher-578-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 535391 INFO (jetty-launcher-578-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.6.1
[junit4] 2> 535391 INFO (jetty-launcher-578-thread-3) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@68bdb174{HTTP/1.1,[http/1.1]}{127.0.0.1:41535}
[junit4] 2> 535391 INFO (jetty-launcher-578-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 535391 INFO (jetty-launcher-578-thread-3) [ ]
o.e.j.s.Server Started @537465ms
[junit4] 2> 535391 INFO (jetty-launcher-578-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 535391 INFO (jetty-launcher-578-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@11792081{HTTP/1.1,[http/1.1]}{127.0.0.1:45497}
[junit4] 2> 535391 INFO (jetty-launcher-578-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-09-01T22:44:32.398Z
[junit4] 2> 535391 INFO (jetty-launcher-578-thread-1) [ ]
o.e.j.s.Server Started @537465ms
[junit4] 2> 535391 INFO (jetty-launcher-578-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=45497}
[junit4] 2> 535391 INFO (jetty-launcher-578-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=41535}
[junit4] 2> 535392 ERROR (jetty-launcher-578-thread-3) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 535392 INFO (jetty-launcher-578-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.6.1
[junit4] 2> 535392 INFO (jetty-launcher-578-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 535392 INFO (jetty-launcher-578-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 535392 INFO (jetty-launcher-578-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-09-01T22:44:32.399Z
[junit4] 2> 535392 INFO (jetty-launcher-578-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 535391 ERROR (jetty-launcher-578-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 535393 INFO (jetty-launcher-578-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 535393 INFO (jetty-launcher-578-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.6.1
[junit4] 2> 535394 INFO (jetty-launcher-578-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 535394 INFO (jetty-launcher-578-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 535394 INFO (jetty-launcher-578-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-09-01T22:44:32.401Z
[junit4] 2> 535395 INFO (jetty-launcher-578-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 535395 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 0x15e3f9dfd9b0001, 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> 535402 INFO (jetty-launcher-578-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 535403 INFO (jetty-launcher-578-thread-2) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 535404 INFO (jetty-launcher-578-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34785/solr
[junit4] 2> 535405 INFO (jetty-launcher-578-thread-4) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 535405 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 0x15e3f9dfd9b0003, 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> 535408 INFO (jetty-launcher-578-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34785/solr
[junit4] 2> 535409 INFO (jetty-launcher-578-thread-3) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 535410 INFO (jetty-launcher-578-thread-1) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 535413 INFO (jetty-launcher-578-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34785/solr
[junit4] 2> 535413 INFO (jetty-launcher-578-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34785/solr
[junit4] 2> 535426 INFO (jetty-launcher-578-thread-4)
[n:127.0.0.1:39241_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 535426 INFO (jetty-launcher-578-thread-3)
[n:127.0.0.1:41535_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 535426 INFO (jetty-launcher-578-thread-1)
[n:127.0.0.1:45497_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 535426 INFO (jetty-launcher-578-thread-2)
[n:127.0.0.1:36239_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 535427 INFO (jetty-launcher-578-thread-4)
[n:127.0.0.1:39241_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:39241_solr
[junit4] 2> 535427 INFO (jetty-launcher-578-thread-1)
[n:127.0.0.1:45497_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:45497_solr
[junit4] 2> 535427 INFO (jetty-launcher-578-thread-3)
[n:127.0.0.1:41535_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41535_solr
[junit4] 2> 535427 INFO (jetty-launcher-578-thread-2)
[n:127.0.0.1:36239_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:36239_solr
[junit4] 2> 535427 INFO (jetty-launcher-578-thread-4)
[n:127.0.0.1:39241_solr ] o.a.s.c.Overseer Overseer
(id=98586189645938698-127.0.0.1:39241_solr-n_0000000000) starting
[junit4] 2> 535427 INFO
(zkCallback-593-thread-1-processing-n:127.0.0.1:36239_solr)
[n:127.0.0.1:36239_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 535427 INFO
(zkCallback-599-thread-1-processing-n:127.0.0.1:41535_solr)
[n:127.0.0.1:41535_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 535430 INFO
(zkCallback-598-thread-1-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 535430 INFO
(zkCallback-600-thread-1-processing-n:127.0.0.1:45497_solr)
[n:127.0.0.1:45497_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 535430 INFO
(zkCallback-593-thread-1-processing-n:127.0.0.1:36239_solr)
[n:127.0.0.1:36239_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 535430 INFO
(zkCallback-599-thread-1-processing-n:127.0.0.1:41535_solr)
[n:127.0.0.1:41535_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 535430 INFO
(zkCallback-600-thread-2-processing-n:127.0.0.1:45497_solr)
[n:127.0.0.1:45497_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 535433 INFO
(OverseerStateUpdate-98586189645938698-127.0.0.1:39241_solr-n_0000000000)
[n:127.0.0.1:39241_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 535438 INFO (jetty-launcher-578-thread-4)
[n:127.0.0.1:39241_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:39241_solr
[junit4] 2> 535439 INFO
(zkCallback-593-thread-1-processing-n:127.0.0.1:36239_solr)
[n:127.0.0.1:36239_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 535439 INFO
(zkCallback-599-thread-1-processing-n:127.0.0.1:41535_solr)
[n:127.0.0.1:41535_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 535441 INFO
(zkCallback-600-thread-2-processing-n:127.0.0.1:45497_solr)
[n:127.0.0.1:45497_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 535441 INFO
(zkCallback-598-thread-1-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 535456 INFO (jetty-launcher-578-thread-2)
[n:127.0.0.1:36239_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_3BAACB4C89E0B94E-001/tempDir-001/node2/.
[junit4] 2> 535457 INFO (jetty-launcher-578-thread-3)
[n:127.0.0.1:41535_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_3BAACB4C89E0B94E-001/tempDir-001/node3/.
[junit4] 2> 535477 INFO (jetty-launcher-578-thread-4)
[n:127.0.0.1:39241_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_3BAACB4C89E0B94E-001/tempDir-001/node4/.
[junit4] 2> 535492 INFO (jetty-launcher-578-thread-1)
[n:127.0.0.1:45497_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_3BAACB4C89E0B94E-001/tempDir-001/node1/.
[junit4] 2> 535503 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[3BAACB4C89E0B94E]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 535504 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[3BAACB4C89E0B94E]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34785/solr ready
[junit4] 2> 535523 INFO
(TEST-CollectionsAPIDistributedZkTest.deleteCollectionOnlyInZk-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.SolrTestCaseJ4 ###Starting deleteCollectionOnlyInZk
[junit4] 2> 535524 INFO
(TEST-CollectionsAPIDistributedZkTest.deleteCollectionOnlyInZk-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 535527 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=onlyinzk&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 535532 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=onlyinzk&action=DELETE&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 535535 INFO (qtp2081668885-3658) [n:127.0.0.1:36239_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 535535 INFO (qtp2081668885-3658) [n:127.0.0.1:36239_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> 535536 INFO (qtp2081668885-3663) [n:127.0.0.1:36239_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf&name=onlyinzk&action=CREATE&numShards=2&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 535537 INFO
(OverseerThreadFactory-1477-thread-2-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_solr ] o.a.s.c.CreateCollectionCmd Create collection
onlyinzk
[junit4] 2> 535537 INFO
(OverseerCollectionConfigSetProcessor-98586189645938698-127.0.0.1:39241_solr-n_0000000000)
[n:127.0.0.1:39241_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> 535641 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=onlyinzk_shard1_replica1&action=CREATE&numShards=2&collection=onlyinzk&shard=shard1&wt=javabin&version=2
[junit4] 2> 535641 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=onlyinzk_shard2_replica1&action=CREATE&numShards=2&collection=onlyinzk&shard=shard2&wt=javabin&version=2
[junit4] 2> 535641 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 535641 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 535744 INFO
(zkCallback-599-thread-1-processing-n:127.0.0.1:41535_solr)
[n:127.0.0.1:41535_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 535744 INFO
(zkCallback-593-thread-1-processing-n:127.0.0.1:36239_solr)
[n:127.0.0.1:36239_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 536656 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.6.1
[junit4] 2> 536656 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.6.1
[junit4] 2> 536677 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.s.IndexSchema
[onlyinzk_shard1_replica1] Schema name=minimal
[junit4] 2> 536677 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.s.IndexSchema
[onlyinzk_shard2_replica1] Schema name=minimal
[junit4] 2> 536680 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.s.IndexSchema Loaded
schema minimal/1.1 with uniqueid field id
[junit4] 2> 536680 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.s.IndexSchema Loaded
schema minimal/1.1 with uniqueid field id
[junit4] 2> 536680 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.CoreContainer Creating
SolrCore 'onlyinzk_shard2_replica1' using configuration from collection
onlyinzk, trusted=true
[junit4] 2> 536680 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.CoreContainer Creating
SolrCore 'onlyinzk_shard1_replica1' using configuration from collection
onlyinzk, trusted=true
[junit4] 2> 536681 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 536681 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 536681 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.SolrCore
[[onlyinzk_shard2_replica1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3BAACB4C89E0B94E-001/tempDir-001/node3/onlyinzk_shard2_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3BAACB4C89E0B94E-001/tempDir-001/node3/./onlyinzk_shard2_replica1/data/]
[junit4] 2> 536681 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.SolrCore
[[onlyinzk_shard1_replica1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3BAACB4C89E0B94E-001/tempDir-001/node2/onlyinzk_shard1_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3BAACB4C89E0B94E-001/tempDir-001/node2/./onlyinzk_shard1_replica1/data/]
[junit4] 2> 536711 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 536711 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 536711 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 536711 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 536712 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 536712 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 536712 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 536712 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 536712 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.s.SolrIndexSearcher
Opening [Searcher@5bea798b[onlyinzk_shard1_replica1] main]
[junit4] 2> 536712 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.s.SolrIndexSearcher
Opening [Searcher@30a66170[onlyinzk_shard2_replica1] main]
[junit4] 2> 536713 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 536713 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 536713 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 536713 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 536713 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 536713 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 536718 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1577379035847065600
[junit4] 2> 536718 INFO
(searcherExecutor-1487-thread-1-processing-n:127.0.0.1:36239_solr
x:onlyinzk_shard1_replica1 s:shard1 c:onlyinzk) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.SolrCore
[onlyinzk_shard1_replica1] Registered new searcher
Searcher@5bea798b[onlyinzk_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 536718 INFO
(searcherExecutor-1486-thread-1-processing-n:127.0.0.1:41535_solr
x:onlyinzk_shard2_replica1 s:shard2 c:onlyinzk) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.SolrCore
[onlyinzk_shard2_replica1] Registered new searcher
Searcher@30a66170[onlyinzk_shard2_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 536718 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1577379035847065600
[junit4] 2> 536721 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 536721 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 536721 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 536721 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.SyncStrategy Sync
replicas to http://127.0.0.1:41535/solr/onlyinzk_shard2_replica1/
[junit4] 2> 536721 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 536721 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.SyncStrategy Sync
Success - now sync replicas to me
[junit4] 2> 536721 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.SyncStrategy Sync
replicas to http://127.0.0.1:36239/solr/onlyinzk_shard1_replica1/
[junit4] 2> 536721 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.SyncStrategy
http://127.0.0.1:41535/solr/onlyinzk_shard2_replica1/ has no replicas
[junit4] 2> 536721 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.SyncStrategy Sync
Success - now sync replicas to me
[junit4] 2> 536721 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 536721 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.SyncStrategy
http://127.0.0.1:36239/solr/onlyinzk_shard1_replica1/ has no replicas
[junit4] 2> 536721 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 536723 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:36239/solr/onlyinzk_shard1_replica1/ shard1
[junit4] 2> 536723 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:41535/solr/onlyinzk_shard2_replica1/ shard2
[junit4] 2> 536824 INFO
(zkCallback-593-thread-1-processing-n:127.0.0.1:36239_solr)
[n:127.0.0.1:36239_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 536824 INFO
(zkCallback-599-thread-1-processing-n:127.0.0.1:41535_solr)
[n:127.0.0.1:41535_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 536873 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.ZkController I am the
leader, no recovery necessary
[junit4] 2> 536873 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.ZkController I am the
leader, no recovery necessary
[junit4] 2> 536876 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=onlyinzk_shard1_replica1&action=CREATE&numShards=2&collection=onlyinzk&shard=shard1&wt=javabin&version=2}
status=0 QTime=1236
[junit4] 2> 536879 INFO (qtp1746577863-3635) [n:127.0.0.1:41535_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=onlyinzk_shard2_replica1&action=CREATE&numShards=2&collection=onlyinzk&shard=shard2&wt=javabin&version=2}
status=0 QTime=1238
[junit4] 2> 536884 INFO (qtp2081668885-3663) [n:127.0.0.1:36239_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> 536986 INFO
(zkCallback-593-thread-1-processing-n:127.0.0.1:36239_solr)
[n:127.0.0.1:36239_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 536986 INFO
(zkCallback-599-thread-1-processing-n:127.0.0.1:41535_solr)
[n:127.0.0.1:41535_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 537539 INFO
(OverseerCollectionConfigSetProcessor-98586189645938698-127.0.0.1:39241_solr-n_0000000000)
[n:127.0.0.1:39241_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> 537884 INFO (qtp2081668885-3663) [n:127.0.0.1:36239_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf&name=onlyinzk&action=CREATE&numShards=2&wt=javabin&version=2}
status=0 QTime=2348
[junit4] 2> 537886 INFO (qtp2081668885-3634) [n:127.0.0.1:36239_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 537886 INFO (qtp2081668885-3634) [n:127.0.0.1:36239_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> 537887 INFO
(TEST-CollectionsAPIDistributedZkTest.deleteCollectionOnlyInZk-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.SolrTestCaseJ4 ###Ending deleteCollectionOnlyInZk
[junit4] 2> 537916 INFO
(TEST-CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.SolrTestCaseJ4 ###Starting deletePartiallyCreatedCollection
[junit4] 2> 537917 INFO
(TEST-CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 537919 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=onlyinzk&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 537920 INFO
(OverseerThreadFactory-1477-thread-3-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 537922 INFO (qtp2081668885-3658) [n:127.0.0.1:36239_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.onlyinzk.shard1.replica1
[junit4] 2> 537923 INFO (qtp1746577863-3662) [n:127.0.0.1:41535_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.onlyinzk.shard2.replica1
[junit4] 2> 537923 INFO (qtp2081668885-3658) [n:127.0.0.1:36239_solr ]
o.a.s.c.SolrCore [onlyinzk_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@53bccac8
[junit4] 2> 537924 INFO (qtp1746577863-3662) [n:127.0.0.1:41535_solr ]
o.a.s.c.SolrCore [onlyinzk_shard2_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@6ac5400f
[junit4] 2> 537931 INFO (qtp2081668885-3658) [n:127.0.0.1:36239_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.onlyinzk.shard1.replica1
[junit4] 2> 537938 INFO (qtp1746577863-3662) [n:127.0.0.1:41535_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.onlyinzk.shard2.replica1
[junit4] 2> 537939 INFO (qtp2081668885-3658) [n:127.0.0.1:36239_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={deleteInstanceDir=true&core=onlyinzk_shard1_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=17
[junit4] 2> 537940 INFO (qtp1746577863-3662) [n:127.0.0.1:41535_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={deleteInstanceDir=true&core=onlyinzk_shard2_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=17
[junit4] 2> 538550 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=onlyinzk&action=DELETE&wt=javabin&version=2} status=0 QTime=631
[junit4] 2> 538552 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr ]
o.a.s.u.TestInjection Inject random core creation delay of 0s
[junit4] 2> 538552 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_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_3BAACB4C89E0B94E-001/tempDir-002&collection.configName=conf&name=halfdeletedcollection_shard1_replica1&action=CREATE&numShards=2&collection=halfdeletedcollection&wt=javabin&version=2
[junit4] 2> 538553 INFO
(OverseerStateUpdate-98586189645938698-127.0.0.1:39241_solr-n_0000000000)
[n:127.0.0.1:39241_solr ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard2
[junit4] 2> 539559 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
[junit4] 2> 539563 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.s.IndexSchema [halfdeletedcollection_shard1_replica1] Schema name=minimal
[junit4] 2> 539565 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 539565 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_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> 539565 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 539566 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_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_3BAACB4C89E0B94E-001/tempDir-001/node2/halfdeletedcollection_shard1_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3BAACB4C89E0B94E-001/tempDir-002/]
[junit4] 2> 539598 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 539598 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_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> 539599 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 539599 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 539599 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@4dadfc7e[halfdeletedcollection_shard1_replica1] main]
[junit4] 2> 539600 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 539601 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_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> 539601 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 539601 INFO
(searcherExecutor-1496-thread-1-processing-n:127.0.0.1:36239_solr
x:halfdeletedcollection_shard1_replica1 c:halfdeletedcollection)
[n:127.0.0.1:36239_solr c:halfdeletedcollection
x:halfdeletedcollection_shard1_replica1] o.a.s.c.SolrCore
[halfdeletedcollection_shard1_replica1] Registered new searcher
Searcher@4dadfc7e[halfdeletedcollection_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 539601 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_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 1577379038870110208
[junit4] 2> 539606 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 539606 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 539606 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:36239/solr/halfdeletedcollection_shard1_replica1/
[junit4] 2> 539606 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 539606 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SyncStrategy
http://127.0.0.1:36239/solr/halfdeletedcollection_shard1_replica1/ has no
replicas
[junit4] 2> 539606 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 539608 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:36239/solr/halfdeletedcollection_shard1_replica1/ shard2
[junit4] 2> 539713 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 539714 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_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_3BAACB4C89E0B94E-001/tempDir-002&collection.configName=conf&name=halfdeletedcollection_shard1_replica1&action=CREATE&numShards=2&collection=halfdeletedcollection&wt=javabin&version=2}
status=0 QTime=1161
[junit4] 2> 539715 INFO (qtp2081668885-3634) [n:127.0.0.1:36239_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> 539716 INFO
(OverseerCollectionConfigSetProcessor-98586189645938698-127.0.0.1:39241_solr-n_0000000000)
[n:127.0.0.1:39241_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> 539716 INFO
(OverseerThreadFactory-1477-thread-4-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 539718 INFO (qtp2081668885-3663) [n:127.0.0.1:36239_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.halfdeletedcollection.shard1.replica1
[junit4] 2> 539718 INFO (qtp2081668885-3663) [n:127.0.0.1:36239_solr ]
o.a.s.c.SolrCore [halfdeletedcollection_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@4e12e2dc
[junit4] 2> 539720 INFO (qtp2081668885-3663) [n:127.0.0.1:36239_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.halfdeletedcollection.shard1.replica1
[junit4] 2> 539721 INFO (qtp2081668885-3663) [n:127.0.0.1:36239_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=3
[junit4] 2> 540424 INFO (qtp2081668885-3634) [n:127.0.0.1:36239_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=halfdeletedcollection&action=DELETE&wt=javabin&version=2} status=0
QTime=708
[junit4] 2> 540425 INFO (qtp2081668885-3658) [n:127.0.0.1:36239_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 540425 INFO (qtp2081668885-3658) [n:127.0.0.1:36239_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> 540427 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_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> 540428 INFO
(OverseerThreadFactory-1477-thread-5-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_solr ] o.a.s.c.CreateCollectionCmd Create collection
halfdeletedcollection
[junit4] 2> 540428 INFO
(OverseerCollectionConfigSetProcessor-98586189645938698-127.0.0.1:39241_solr-n_0000000000)
[n:127.0.0.1:39241_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> 540643 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_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> 540644 INFO (qtp973042556-3637) [n:127.0.0.1:39241_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> 540644 INFO (qtp973042556-3637) [n:127.0.0.1:39241_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 540747 INFO
(zkCallback-598-thread-1-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_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> 540747 INFO
(zkCallback-593-thread-2-processing-n:127.0.0.1:36239_solr)
[n:127.0.0.1:36239_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> 541650 INFO (qtp973042556-3637) [n:127.0.0.1:39241_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
[junit4] 2> 541651 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
[junit4] 2> 541656 INFO (qtp973042556-3637) [n:127.0.0.1:39241_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.s.IndexSchema [halfdeletedcollection_shard1_replica1] Schema name=minimal
[junit4] 2> 541658 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.s.IndexSchema [halfdeletedcollection_shard2_replica1] Schema name=minimal
[junit4] 2> 541658 INFO (qtp973042556-3637) [n:127.0.0.1:39241_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> 541658 INFO (qtp973042556-3637) [n:127.0.0.1:39241_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> 541658 INFO (qtp973042556-3637) [n:127.0.0.1:39241_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 541658 INFO (qtp973042556-3637) [n:127.0.0.1:39241_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_3BAACB4C89E0B94E-001/tempDir-001/node4/halfdeletedcollection_shard1_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3BAACB4C89E0B94E-001/tempDir-001/node4/./halfdeletedcollection_shard1_replica1/data/]
[junit4] 2> 541659 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_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> 541659 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_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> 541660 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 541660 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_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_3BAACB4C89E0B94E-001/tempDir-001/node2/halfdeletedcollection_shard2_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3BAACB4C89E0B94E-001/tempDir-001/node2/./halfdeletedcollection_shard2_replica1/data/]
[junit4] 2> 541686 INFO (qtp973042556-3637) [n:127.0.0.1:39241_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 541686 INFO (qtp973042556-3637) [n:127.0.0.1:39241_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> 541687 INFO (qtp973042556-3637) [n:127.0.0.1:39241_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 541687 INFO (qtp973042556-3637) [n:127.0.0.1:39241_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 541687 INFO (qtp973042556-3637) [n:127.0.0.1:39241_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@2de02c0d[halfdeletedcollection_shard1_replica1] main]
[junit4] 2> 541688 INFO (qtp973042556-3637) [n:127.0.0.1:39241_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 541688 INFO (qtp973042556-3637) [n:127.0.0.1:39241_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> 541688 INFO (qtp973042556-3637) [n:127.0.0.1:39241_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 541688 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 541688 INFO
(searcherExecutor-1501-thread-1-processing-n:127.0.0.1:39241_solr
x:halfdeletedcollection_shard1_replica1 s:shard1 c:halfdeletedcollection)
[n:127.0.0.1:39241_solr c:halfdeletedcollection s:shard1
x:halfdeletedcollection_shard1_replica1] o.a.s.c.SolrCore
[halfdeletedcollection_shard1_replica1] Registered new searcher
Searcher@2de02c0d[halfdeletedcollection_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 541688 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_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> 541689 INFO (qtp973042556-3637) [n:127.0.0.1:39241_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 1577379041059536896
[junit4] 2> 541689 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 541689 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 541690 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@46bb9549[halfdeletedcollection_shard2_replica1] main]
[junit4] 2> 541691 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 541692 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_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> 541692 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 541693 INFO
(searcherExecutor-1502-thread-1-processing-n:127.0.0.1:36239_solr
x:halfdeletedcollection_shard2_replica1 s:shard2 c:halfdeletedcollection)
[n:127.0.0.1:36239_solr c:halfdeletedcollection s:shard2
x:halfdeletedcollection_shard2_replica1] o.a.s.c.SolrCore
[halfdeletedcollection_shard2_replica1] Registered new searcher
Searcher@46bb9549[halfdeletedcollection_shard2_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 541693 INFO (qtp973042556-3637) [n:127.0.0.1:39241_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 541693 INFO (qtp973042556-3637) [n:127.0.0.1:39241_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> 541693 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_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 1577379041063731200
[junit4] 2> 541693 INFO (qtp973042556-3637) [n:127.0.0.1:39241_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:39241/solr/halfdeletedcollection_shard1_replica1/
[junit4] 2> 541693 INFO (qtp973042556-3637) [n:127.0.0.1:39241_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 541693 INFO (qtp973042556-3637) [n:127.0.0.1:39241_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SyncStrategy
http://127.0.0.1:39241/solr/halfdeletedcollection_shard1_replica1/ has no
replicas
[junit4] 2> 541693 INFO (qtp973042556-3637) [n:127.0.0.1:39241_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 541694 INFO (qtp973042556-3637) [n:127.0.0.1:39241_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:39241/solr/halfdeletedcollection_shard1_replica1/ shard1
[junit4] 2> 541696 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 541696 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_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> 541696 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:36239/solr/halfdeletedcollection_shard2_replica1/
[junit4] 2> 541696 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 541696 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.SyncStrategy
http://127.0.0.1:36239/solr/halfdeletedcollection_shard2_replica1/ has no
replicas
[junit4] 2> 541696 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 541697 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:36239/solr/halfdeletedcollection_shard2_replica1/ shard2
[junit4] 2> 541799 INFO
(zkCallback-598-thread-1-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_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> 541799 INFO
(zkCallback-593-thread-2-processing-n:127.0.0.1:36239_solr)
[n:127.0.0.1:36239_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> 541845 INFO (qtp973042556-3637) [n:127.0.0.1:39241_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 541846 INFO (qtp973042556-3637) [n:127.0.0.1:39241_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=1202
[junit4] 2> 541848 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 541848 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_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=1205
[junit4] 2> 541850 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_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> 541949 INFO
(zkCallback-593-thread-2-processing-n:127.0.0.1:36239_solr)
[n:127.0.0.1:36239_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> 541949 INFO
(zkCallback-598-thread-1-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_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> 542429 INFO
(OverseerCollectionConfigSetProcessor-98586189645938698-127.0.0.1:39241_solr-n_0000000000)
[n:127.0.0.1:39241_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> 542850 INFO (qtp2081668885-3653) [n:127.0.0.1:36239_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=2423
[junit4] 2> 542852 INFO (qtp2081668885-3663) [n:127.0.0.1:36239_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 542852 INFO (qtp2081668885-3663) [n:127.0.0.1:36239_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> 542852 INFO
(TEST-CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.SolrTestCaseJ4 ###Ending deletePartiallyCreatedCollection
[junit4] 2> 542872 INFO
(TEST-CollectionsAPIDistributedZkTest.testMissingNumShards-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testMissingNumShards
[junit4] 2> 542873 INFO
(TEST-CollectionsAPIDistributedZkTest.testMissingNumShards-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 542874 INFO (qtp2081668885-3658) [n:127.0.0.1:36239_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> 542875 INFO
(OverseerThreadFactory-1477-thread-5-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 542877 INFO (qtp973042556-3656) [n:127.0.0.1:39241_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.halfdeletedcollection.shard1.replica1
[junit4] 2> 542877 INFO (qtp2081668885-3634) [n:127.0.0.1:36239_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.halfdeletedcollection.shard2.replica1
[junit4] 2> 542877 INFO (qtp973042556-3656) [n:127.0.0.1:39241_solr ]
o.a.s.c.SolrCore [halfdeletedcollection_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@3b2b2bb4
[junit4] 2> 542877 INFO (qtp2081668885-3634) [n:127.0.0.1:36239_solr ]
o.a.s.c.SolrCore [halfdeletedcollection_shard2_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@3a5ddad5
[junit4] 2> 542879 INFO (qtp973042556-3656) [n:127.0.0.1:39241_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.halfdeletedcollection.shard1.replica1
[junit4] 2> 542880 INFO (qtp2081668885-3634) [n:127.0.0.1:36239_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.halfdeletedcollection.shard2.replica1
[junit4] 2> 542880 INFO (qtp973042556-3656) [n:127.0.0.1:39241_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=3
[junit4] 2> 542881 INFO (qtp2081668885-3634) [n:127.0.0.1:36239_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=4
[junit4] 2> 543583 INFO (qtp2081668885-3658) [n:127.0.0.1:36239_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=halfdeletedcollection&action=DELETE&wt=javabin&version=2} status=0
QTime=708
[junit4] 2> 543585 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=10&collection.configName=conf&name=acollection&action=CREATE&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 543586 INFO
(OverseerThreadFactory-1477-thread-5-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_solr ] o.a.s.c.CreateCollectionCmd Create collection
acollection
[junit4] 2> 543586 ERROR
(OverseerThreadFactory-1477-thread-5-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_solr ] o.a.s.c.OverseerCollectionMessageHandler
Collection: acollection operation: create
failed:org.apache.solr.common.SolrException: numShards is a required param
(when using CompositeId router).
[junit4] 2> at
org.apache.solr.cloud.CreateCollectionCmd.call(CreateCollectionCmd.java:112)
[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> 543588 INFO
(OverseerCollectionConfigSetProcessor-98586189645938698-127.0.0.1:39241_solr-n_0000000000)
[n:127.0.0.1:39241_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> 543595 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=10&collection.configName=conf&name=acollection&action=CREATE&wt=javabin&version=2}
status=400 QTime=9
[junit4] 2> 543596 INFO
(TEST-CollectionsAPIDistributedZkTest.testMissingNumShards-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testMissingNumShards
[junit4] 2> 543621 INFO
(TEST-CollectionsAPIDistributedZkTest.testMissingRequiredParameters-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testMissingRequiredParameters
[junit4] 2> 543622 INFO
(TEST-CollectionsAPIDistributedZkTest.testMissingRequiredParameters-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 543624 INFO (qtp2081668885-3663) [n:127.0.0.1:36239_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> 543624 ERROR (qtp2081668885-3663) [n:127.0.0.1:36239_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.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> 543626 INFO (qtp2081668885-3663) [n:127.0.0.1:36239_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=CREATE&numShards=2&wt=javabin&version=2} status=400 QTime=1
[junit4] 2> 543626 INFO
(TEST-CollectionsAPIDistributedZkTest.testMissingRequiredParameters-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testMissingRequiredParameters
[junit4] 2> 543650 INFO
(TEST-CollectionsAPIDistributedZkTest.testCreateNodeSet-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateNodeSet
[junit4] 2> 543650 INFO
(TEST-CollectionsAPIDistributedZkTest.testCreateNodeSet-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 543652 INFO (qtp2081668885-3634) [n:127.0.0.1:36239_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=http://127.0.0.1:39241/solr,http://127.0.0.1:45497/solr&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 543653 INFO
(OverseerThreadFactory-1477-thread-5-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_solr ] o.a.s.c.CreateCollectionCmd Create collection
nodeset_collection
[junit4] 2> 543653 WARN
(OverseerThreadFactory-1477-thread-5-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_solr ] o.a.s.c.CreateCollectionCmd It is unusual to
create a collection (nodeset_collection) without cores.
[junit4] 2> 543653 INFO
(OverseerCollectionConfigSetProcessor-98586189645938698-127.0.0.1:39241_solr-n_0000000000)
[n:127.0.0.1:39241_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> 543857 INFO (qtp2081668885-3634) [n:127.0.0.1:36239_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> 543857 INFO (qtp2081668885-3634) [n:127.0.0.1:36239_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=http://127.0.0.1:39241/solr,http://127.0.0.1:45497/solr&wt=javabin&version=2}
status=0 QTime=205
[junit4] 2> 543858 INFO
(TEST-CollectionsAPIDistributedZkTest.testCreateNodeSet-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateNodeSet
[junit4] 2> 543887 INFO
(TEST-CollectionsAPIDistributedZkTest.deleteCollectionRemovesStaleZkCollectionsNode-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
deleteCollectionRemovesStaleZkCollectionsNode
[junit4] 2> 543888 INFO
(TEST-CollectionsAPIDistributedZkTest.deleteCollectionRemovesStaleZkCollectionsNode-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 543890 INFO (qtp2081668885-3658) [n:127.0.0.1:36239_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> 543891 INFO
(OverseerCollectionConfigSetProcessor-98586189645938698-127.0.0.1:39241_solr-n_0000000000)
[n:127.0.0.1:39241_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> 543891 INFO
(OverseerThreadFactory-1477-thread-5-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 544593 INFO (qtp2081668885-3658) [n:127.0.0.1:36239_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=703
[junit4] 2> 544596 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=out_of_sync_collection&action=DELETE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 544597 INFO
(OverseerCollectionConfigSetProcessor-98586189645938698-127.0.0.1:39241_solr-n_0000000000)
[n:127.0.0.1:39241_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> 544599 INFO (qtp2081668885-3638) [n:127.0.0.1:36239_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=out_of_sync_collection&action=DELETE&wt=javabin&version=2}
status=0 QTime=2
[junit4] 2> 544600 INFO (qtp2081668885-3663) [n:127.0.0.1:36239_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 544600 INFO (qtp2081668885-3663) [n:127.0.0.1:36239_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> 544601 INFO
(TEST-CollectionsAPIDistributedZkTest.deleteCollectionRemovesStaleZkCollectionsNode-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.SolrTestCaseJ4 ###Ending
deleteCollectionRemovesStaleZkCollectionsNode
[junit4] 2> 544626 INFO
(TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testTooManyReplicas
[junit4] 2> 544627 INFO
(TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 544629 INFO (qtp2081668885-3634) [n:127.0.0.1:36239_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> 544630 INFO
(OverseerThreadFactory-1477-thread-5-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_solr ] o.a.s.c.CreateCollectionCmd Create collection
collection
[junit4] 2> 544630 WARN
(OverseerThreadFactory-1477-thread-5-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_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> 544630 INFO
(OverseerCollectionConfigSetProcessor-98586189645938698-127.0.0.1:39241_solr-n_0000000000)
[n:127.0.0.1:39241_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000018 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 544630 ERROR
(OverseerThreadFactory-1477-thread-5-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_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> 544635 INFO (qtp2081668885-3634) [n:127.0.0.1:36239_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> 544635 INFO
(TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testTooManyReplicas
[junit4] 2> 544665 INFO
(TEST-CollectionsAPIDistributedZkTest.testCreationAndDeletion-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testCreationAndDeletion
[junit4] 2> 544665 INFO
(TEST-CollectionsAPIDistributedZkTest.testCreationAndDeletion-seed#[3BAACB4C89E0B94E])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 544667 INFO (qtp2081668885-3658) [n:127.0.0.1:36239_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> 544668 INFO
(OverseerThreadFactory-1477-thread-5-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_solr ] o.a.s.c.CreateCollectionCmd Create collection
created_and_deleted
[junit4] 2> 544668 INFO
(OverseerCollectionConfigSetProcessor-98586189645938698-127.0.0.1:39241_solr-n_0000000000)
[n:127.0.0.1:39241_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000020 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 544873 INFO (qtp973042556-3664) [n:127.0.0.1:39241_solr ]
o.a.s.h.a.CoreAdminOperation core cr
[...truncated too long message...]
er-579-thread-4) [ ] o.e.j.s.AbstractConnector Stopped
ServerConnector@11792081{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 622308 INFO (jetty-closer-579-thread-2) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@68bdb174{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 622308 INFO (jetty-closer-579-thread-4) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1953275479
[junit4] 2> 622311 INFO (jetty-closer-579-thread-2) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=2108192106
[junit4] 2> 622311 INFO (jetty-closer-579-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 622311 INFO (jetty-closer-579-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 622311 INFO (jetty-closer-579-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
[junit4] 2> 622311 INFO (jetty-closer-579-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
[junit4] 2> 622311 INFO (jetty-closer-579-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
[junit4] 2> 622311 INFO (jetty-closer-579-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
[junit4] 2> 622311 INFO (jetty-closer-579-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@6af18f0c{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 622312 INFO (jetty-closer-579-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1504273034
[junit4] 2> 622312 INFO (jetty-closer-579-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 622312 INFO (jetty-closer-579-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
[junit4] 2> 622312 INFO (jetty-closer-579-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
[junit4] 2> 622312 INFO (jetty-closer-579-thread-3) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@1e4b42e2{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 622314 INFO (jetty-closer-579-thread-3) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1429940474
[junit4] 2> 622314 INFO (jetty-closer-579-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 622314 INFO (jetty-closer-579-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
[junit4] 2> 622314 INFO (jetty-closer-579-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
[junit4] 2> 622318 INFO
(zkCallback-593-thread-1-processing-n:127.0.0.1:36239_solr)
[n:127.0.0.1:36239_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 622318 INFO
(zkCallback-598-thread-2-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 622318 INFO
(zkCallback-599-thread-3-processing-n:127.0.0.1:41535_solr)
[n:127.0.0.1:41535_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 622321 INFO
(zkCallback-598-thread-2-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 622321 INFO
(zkCallback-593-thread-1-processing-n:127.0.0.1:36239_solr)
[n:127.0.0.1:36239_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 622322 INFO (jetty-closer-579-thread-3) [ ]
o.a.s.c.Overseer Overseer
(id=98586189645938698-127.0.0.1:39241_solr-n_0000000000) closing
[junit4] 2> 622327 INFO
(OverseerStateUpdate-98586189645938698-127.0.0.1:39241_solr-n_0000000000)
[n:127.0.0.1:39241_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:39241_solr
[junit4] 2> 623818 WARN
(zkCallback-600-thread-2-processing-n:127.0.0.1:45497_solr)
[n:127.0.0.1:45497_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 623818 INFO (jetty-closer-579-thread-4) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@b7f7378{/solr,null,UNAVAILABLE}
[junit4] 2> 623820 WARN
(zkCallback-599-thread-3-processing-n:127.0.0.1:41535_solr)
[n:127.0.0.1:41535_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 623820 INFO (jetty-closer-579-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@42cdfd6f{/solr,null,UNAVAILABLE}
[junit4] 2> 623828 WARN
(zkCallback-593-thread-2-processing-n:127.0.0.1:36239_solr)
[n:127.0.0.1:36239_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 623828 WARN
(zkCallback-598-thread-2-processing-n:127.0.0.1:39241_solr)
[n:127.0.0.1:39241_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 623828 INFO (jetty-closer-579-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@4114d789{/solr,null,UNAVAILABLE}
[junit4] 2> 623828 INFO (jetty-closer-579-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@75a63c62{/solr,null,UNAVAILABLE}
[junit4] 2> 623830 ERROR
(SUITE-CollectionsAPIDistributedZkTest-seed#[3BAACB4C89E0B94E]-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> 623830 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[3BAACB4C89E0B94E]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:34785 34785
[junit4] 2> 633872 INFO (Thread-944) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:34785 34785
[junit4] 2> 633874 WARN (Thread-944) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 25 /solr/aliases.json
[junit4] 2> 25 /solr/clusterprops.json
[junit4] 2> 23 /solr/configs/conf
[junit4] 2> 5 /solr/configs/conf2
[junit4] 2> 4 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 292 /solr/clusterstate.json
[junit4] 2> 5
/solr/collections/awhollynewcollection_0/state.json
[junit4] 2> 4
/solr/collections/nodes_used_collection/state.json
[junit4] 2> 4 /solr/collections/addReplicaColl/state.json
[junit4] 2> 4 /solr/collections/reloaded_collection/state.json
[junit4] 2> 3
/solr/collections/acollectionafterbaddelete/state.json
[junit4] 2> 2 /solr/collections/onlyinzk/state.json
[junit4] 2> 2
/solr/overseer_elect/election/98586189645938698-127.0.0.1:39241_solr-n_0000000000
[junit4] 2> 2
/solr/overseer_elect/election/98586189645938699-127.0.0.1:41535_solr-n_0000000001
[junit4] 2> 2
/solr/collections/halfdeletedcollection/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 441 /solr/collections
[junit4] 2> 25 /solr/live_nodes
[junit4] 2>
[junit4] 2> 633877 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[3BAACB4C89E0B94E]-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_3BAACB4C89E0B94E-001
[junit4] 2> NOTE: test params are:
codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST,
chunkSize=1, maxDocsPerChunk=588, blockSize=202),
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST,
chunkSize=1, blockSize=202)), sim=RandomSimilarity(queryNorm=true,coord=crazy):
{}, locale=es-HN, timezone=Africa/Dar_es_Salaam
[junit4] 2> NOTE: Linux 4.10.0-33-generic amd64/Oracle Corporation
1.8.0_144 (64-bit)/cpus=8,threads=1,free=283394384,total=523239424
[junit4] 2> NOTE: All tests run in this JVM: [TestGraphTermsQParserPlugin,
TestUseDocValuesAsStored2, SampleTest, SolrJmxReporterCloudTest, AssignTest,
ShardRoutingTest, SortByFunctionTest, WordBreakSolrSpellCheckerTest,
TestSolrQueryParserDefaultOperatorResource, TestSmileRequest,
IndexBasedSpellCheckerTest, BlockJoinFacetDistribTest, GraphQueryTest,
TestPerFieldSimilarityWithDefaultOverride, DistribCursorPagingTest,
SimplePostToolTest, TestExceedMaxTermLength, TestFieldCache,
ParsingFieldUpdateProcessorsTest, DocValuesTest, TestCollationFieldDocValues,
TestClusterProperties, UpdateRequestProcessorFactoryTest, TestManagedSchemaAPI,
TestDynamicLoading, ConnectionReuseTest, JSONWriterTest,
TestFieldCacheWithThreads, TestRTGBase, ZkStateReaderTest,
DistributedQueueTest, TestConfigReload, TestQueryUtils,
DistributedMLTComponentTest, TestDistributedStatsComponentCardinality,
TestFastOutputStream, TestStressCloudBlindAtomicUpdates, ConnectionManagerTest,
BooleanFieldTest, TestLeaderElectionZkExpiry, TestFuzzyAnalyzedSuggestions,
TestSchemaManager, LeaderInitiatedRecoveryOnShardRestartTest,
TestSimpleTrackingShardHandler, DistributedFacetPivotLargeTest, SparseHLLTest,
DistributedFacetPivotSmallTest, OpenCloseCoreStressTest,
ShardRoutingCustomTest, CopyFieldTest, TestElisionMultitermQuery,
TestInPlaceUpdatesStandalone, TestSQLHandlerNonCloud, TestSearchPerf,
CollectionsAPIDistributedZkTest]
[junit4] Completed [228/713 (1!)] on J1 in 98.61s, 20 tests, 1 failure <<<
FAILURES!
[...truncated 45061 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
