Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.6-Linux/152/
Java: 32bit/jdk1.8.0_144 -client -XX:+UseConcMarkSweepGC
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([2D297F6D227C8661:655C0BD9244FA9F4]: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 11427 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_2D297F6D227C8661-001/init-core-data-001
[junit4] 2> 585798 WARN
(SUITE-CollectionsAPIDistributedZkTest-seed#[2D297F6D227C8661]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3
[junit4] 2> 585799 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[2D297F6D227C8661]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields
[junit4] 2> 585800 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[2D297F6D227C8661]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 585801 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[2D297F6D227C8661]-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_2D297F6D227C8661-001/tempDir-001
[junit4] 2> 585801 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[2D297F6D227C8661]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 585801 INFO (Thread-4169) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 585801 INFO (Thread-4169) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 585803 ERROR (Thread-4169) [ ] 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> 585901 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[2D297F6D227C8661]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:35477
[junit4] 2> 585921 INFO (jetty-launcher-831-thread-4) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 585921 INFO (jetty-launcher-831-thread-3) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 585921 INFO (jetty-launcher-831-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 585921 INFO (jetty-launcher-831-thread-2) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 585927 INFO (jetty-launcher-831-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1ea4193{/solr,null,AVAILABLE}
[junit4] 2> 585927 INFO (jetty-launcher-831-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@eaae66{/solr,null,AVAILABLE}
[junit4] 2> 585930 INFO (jetty-launcher-831-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1ac126a{/solr,null,AVAILABLE}
[junit4] 2> 585932 INFO (jetty-launcher-831-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@114545f{SSL,[ssl,
http/1.1]}{127.0.0.1:39509}
[junit4] 2> 585932 INFO (jetty-launcher-831-thread-1) [ ]
o.e.j.s.Server Started @587136ms
[junit4] 2> 585933 INFO (jetty-launcher-831-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=39509}
[junit4] 2> 585933 INFO (jetty-launcher-831-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@35ac17{SSL,[ssl,
http/1.1]}{127.0.0.1:33061}
[junit4] 2> 585933 ERROR (jetty-launcher-831-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 585933 INFO (jetty-launcher-831-thread-2) [ ]
o.e.j.s.Server Started @587137ms
[junit4] 2> 585933 INFO (jetty-launcher-831-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.6.1
[junit4] 2> 585933 INFO (jetty-launcher-831-thread-4) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@5d39d9{SSL,[ssl,
http/1.1]}{127.0.0.1:40977}
[junit4] 2> 585933 INFO (jetty-launcher-831-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 585933 INFO (jetty-launcher-831-thread-4) [ ]
o.e.j.s.Server Started @587137ms
[junit4] 2> 585933 INFO (jetty-launcher-831-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 585933 INFO (jetty-launcher-831-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=33061}
[junit4] 2> 585934 INFO (jetty-launcher-831-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-09-07T15:47:52.770Z
[junit4] 2> 585933 INFO (jetty-launcher-831-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1b87e77{/solr,null,AVAILABLE}
[junit4] 2> 585933 INFO (jetty-launcher-831-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=40977}
[junit4] 2> 585934 ERROR (jetty-launcher-831-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 585934 ERROR (jetty-launcher-831-thread-4) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 585934 INFO (jetty-launcher-831-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.6.1
[junit4] 2> 585934 INFO (jetty-launcher-831-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.6.1
[junit4] 2> 585934 INFO (jetty-launcher-831-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 585934 INFO (jetty-launcher-831-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 585934 INFO (jetty-launcher-831-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 585934 INFO (jetty-launcher-831-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 585934 INFO (jetty-launcher-831-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-09-07T15:47:52.770Z
[junit4] 2> 585934 INFO (jetty-launcher-831-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-09-07T15:47:52.770Z
[junit4] 2> 585934 INFO (jetty-launcher-831-thread-3) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@1013492{SSL,[ssl,
http/1.1]}{127.0.0.1:38591}
[junit4] 2> 585934 INFO (jetty-launcher-831-thread-3) [ ]
o.e.j.s.Server Started @587138ms
[junit4] 2> 585934 INFO (jetty-launcher-831-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=38591}
[junit4] 2> 585934 ERROR (jetty-launcher-831-thread-3) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 585935 INFO (jetty-launcher-831-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.6.1
[junit4] 2> 585935 INFO (jetty-launcher-831-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 585935 INFO (jetty-launcher-831-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 585935 INFO (jetty-launcher-831-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-09-07T15:47:52.771Z
[junit4] 2> 585943 INFO (jetty-launcher-831-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 585943 INFO (jetty-launcher-831-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 585951 INFO (jetty-launcher-831-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 585951 INFO (jetty-launcher-831-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 585962 INFO (jetty-launcher-831-thread-1) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 585963 INFO (jetty-launcher-831-thread-2) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 585963 INFO (jetty-launcher-831-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35477/solr
[junit4] 2> 585964 INFO (jetty-launcher-831-thread-3) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 585964 INFO (jetty-launcher-831-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35477/solr
[junit4] 2> 585965 INFO (jetty-launcher-831-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35477/solr
[junit4] 2> 585966 INFO (jetty-launcher-831-thread-4) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 585968 INFO (jetty-launcher-831-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35477/solr
[junit4] 2> 585984 INFO (jetty-launcher-831-thread-4)
[n:127.0.0.1:40977_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 585984 INFO (jetty-launcher-831-thread-2)
[n:127.0.0.1:33061_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 585985 INFO (jetty-launcher-831-thread-1)
[n:127.0.0.1:39509_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 585985 INFO (jetty-launcher-831-thread-3)
[n:127.0.0.1:38591_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 585985 INFO (jetty-launcher-831-thread-4)
[n:127.0.0.1:40977_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:40977_solr
[junit4] 2> 585986 INFO (jetty-launcher-831-thread-1)
[n:127.0.0.1:39509_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:39509_solr
[junit4] 2> 585986 INFO (jetty-launcher-831-thread-2)
[n:127.0.0.1:33061_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33061_solr
[junit4] 2> 585986 INFO (jetty-launcher-831-thread-3)
[n:127.0.0.1:38591_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:38591_solr
[junit4] 2> 585986 INFO (jetty-launcher-831-thread-4)
[n:127.0.0.1:40977_solr ] o.a.s.c.Overseer Overseer
(id=98618525131603980-127.0.0.1:40977_solr-n_0000000000) starting
[junit4] 2> 585987 INFO
(zkCallback-852-thread-1-processing-n:127.0.0.1:38591_solr)
[n:127.0.0.1:38591_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 585987 INFO
(zkCallback-851-thread-1-processing-n:127.0.0.1:33061_solr)
[n:127.0.0.1:33061_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 585988 INFO
(zkCallback-850-thread-1-processing-n:127.0.0.1:39509_solr)
[n:127.0.0.1:39509_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 585989 INFO
(zkCallback-853-thread-1-processing-n:127.0.0.1:40977_solr)
[n:127.0.0.1:40977_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 586002 INFO (jetty-launcher-831-thread-4)
[n:127.0.0.1:40977_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40977_solr
[junit4] 2> 586003 INFO
(zkCallback-851-thread-1-processing-n:127.0.0.1:33061_solr)
[n:127.0.0.1:33061_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 586003 INFO
(zkCallback-850-thread-1-processing-n:127.0.0.1:39509_solr)
[n:127.0.0.1:39509_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 586003 INFO
(zkCallback-852-thread-1-processing-n:127.0.0.1:38591_solr)
[n:127.0.0.1:38591_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 586004 INFO
(zkCallback-853-thread-2-processing-n:127.0.0.1:40977_solr)
[n:127.0.0.1:40977_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 586125 INFO (jetty-launcher-831-thread-1)
[n:127.0.0.1:39509_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_2D297F6D227C8661-001/tempDir-001/node1/.
[junit4] 2> 586127 INFO (jetty-launcher-831-thread-3)
[n:127.0.0.1:38591_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_2D297F6D227C8661-001/tempDir-001/node3/.
[junit4] 2> 586235 INFO (jetty-launcher-831-thread-4)
[n:127.0.0.1:40977_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_2D297F6D227C8661-001/tempDir-001/node4/.
[junit4] 2> 586284 INFO (jetty-launcher-831-thread-2)
[n:127.0.0.1:33061_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_2D297F6D227C8661-001/tempDir-001/node2/.
[junit4] 2> 586294 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[2D297F6D227C8661]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 586295 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[2D297F6D227C8661]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35477/solr ready
[junit4] 2> 586314 INFO
(TEST-CollectionsAPIDistributedZkTest.testDeleteNonExistentCollection-seed#[2D297F6D227C8661])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testDeleteNonExistentCollection
[junit4] 2> 586315 INFO
(TEST-CollectionsAPIDistributedZkTest.testDeleteNonExistentCollection-seed#[2D297F6D227C8661])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 586398 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=unknown_collection&action=DELETE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 586404 INFO
(OverseerThreadFactory-1722-thread-1-processing-n:127.0.0.1:40977_solr)
[n:127.0.0.1:40977_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 586405 ERROR
(OverseerThreadFactory-1722-thread-1-processing-n:127.0.0.1:40977_solr)
[n:127.0.0.1:40977_solr ] o.a.s.c.OverseerCollectionMessageHandler
Collection: unknown_collection operation: delete
failed:org.apache.solr.common.SolrException: Could not find collection :
unknown_collection
[junit4] 2> at
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:194)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.collectionCmd(OverseerCollectionMessageHandler.java:846)
[junit4] 2> at
org.apache.solr.cloud.DeleteCollectionCmd.call(DeleteCollectionCmd.java:88)
[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> 586434 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=unknown_collection&action=DELETE&wt=javabin&version=2} status=400
QTime=36
[junit4] 2> 586440 INFO (qtp3255001-7747) [n:127.0.0.1:33061_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=2&collection.configName=conf&name=acollectionafterbaddelete&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 586443 INFO
(OverseerThreadFactory-1722-thread-2-processing-n:127.0.0.1:40977_solr)
[n:127.0.0.1:40977_solr ] o.a.s.c.CreateCollectionCmd Create collection
acollectionafterbaddelete
[junit4] 2> 586444 INFO
(OverseerCollectionConfigSetProcessor-98618525131603980-127.0.0.1:40977_solr-n_0000000000)
[n:127.0.0.1:40977_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> 586564 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=acollectionafterbaddelete_shard1_replica1&action=CREATE&numShards=1&collection=acollectionafterbaddelete&shard=shard1&wt=javabin&version=2
[junit4] 2> 586565 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 586609 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=acollectionafterbaddelete_shard1_replica2&action=CREATE&numShards=1&collection=acollectionafterbaddelete&shard=shard1&wt=javabin&version=2
[junit4] 2> 586610 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 586715 INFO
(zkCallback-851-thread-1-processing-n:127.0.0.1:33061_solr)
[n:127.0.0.1:33061_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/acollectionafterbaddelete/state.json] for collection
[acollectionafterbaddelete] has occurred - updating... (live nodes size: [4])
[junit4] 2> 586715 INFO
(zkCallback-850-thread-1-processing-n:127.0.0.1:39509_solr)
[n:127.0.0.1:39509_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/acollectionafterbaddelete/state.json] for collection
[acollectionafterbaddelete] has occurred - updating... (live nodes size: [4])
[junit4] 2> 587596 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 6.6.1
[junit4] 2> 587622 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.s.IndexSchema
[acollectionafterbaddelete_shard1_replica1] Schema name=minimal
[junit4] 2> 587634 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 587634 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica2] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 6.6.1
[junit4] 2> 587634 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.c.CoreContainer Creating
SolrCore 'acollectionafterbaddelete_shard1_replica1' using configuration from
collection acollectionafterbaddelete, trusted=true
[junit4] 2> 587635 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 587635 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.c.SolrCore
[[acollectionafterbaddelete_shard1_replica1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D297F6D227C8661-001/tempDir-001/node2/acollectionafterbaddelete_shard1_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D297F6D227C8661-001/tempDir-001/node2/./acollectionafterbaddelete_shard1_replica1/data/]
[junit4] 2> 587648 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica2] o.a.s.s.IndexSchema
[acollectionafterbaddelete_shard1_replica2] Schema name=minimal
[junit4] 2> 587653 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica2] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 587653 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica2] o.a.s.c.CoreContainer Creating
SolrCore 'acollectionafterbaddelete_shard1_replica2' using configuration from
collection acollectionafterbaddelete, trusted=true
[junit4] 2> 587653 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica2] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 587653 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica2] o.a.s.c.SolrCore
[[acollectionafterbaddelete_shard1_replica2] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D297F6D227C8661-001/tempDir-001/node1/acollectionafterbaddelete_shard1_replica2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D297F6D227C8661-001/tempDir-001/node1/./acollectionafterbaddelete_shard1_replica2/data/]
[junit4] 2> 587686 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 587687 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 587688 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 587688 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 587689 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@137f655[acollectionafterbaddelete_shard1_replica1] main]
[junit4] 2> 587695 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 587695 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 587695 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000
[junit4] 2> 587696 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica2] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 587696 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica2] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 587697 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica2] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 587697 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica2] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 587697 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica2] o.a.s.s.SolrIndexSearcher Opening
[Searcher@1a4af32[acollectionafterbaddelete_shard1_replica2] main]
[junit4] 2> 587698 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica2] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 587699 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica2] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 587699 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica2] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000
[junit4] 2> 587701 INFO
(searcherExecutor-1731-thread-1-processing-n:127.0.0.1:33061_solr
x:acollectionafterbaddelete_shard1_replica1 s:shard1
c:acollectionafterbaddelete) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.c.SolrCore
[acollectionafterbaddelete_shard1_replica1] Registered new searcher
Searcher@137f655[acollectionafterbaddelete_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 587701 INFO
(searcherExecutor-1732-thread-1-processing-n:127.0.0.1:39509_solr
x:acollectionafterbaddelete_shard1_replica2 s:shard1
c:acollectionafterbaddelete) [n:127.0.0.1:39509_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica2] o.a.s.c.SolrCore
[acollectionafterbaddelete_shard1_replica2] Registered new searcher
Searcher@1a4af32[acollectionafterbaddelete_shard1_replica2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 587701 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.u.UpdateLog Could not find
max version in index or recent updates, using new clock 1577896404096909312
[junit4] 2> 587701 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica2] o.a.s.u.UpdateLog Could not find
max version in index or recent updates, using new clock 1577896404096909312
[junit4] 2> 587706 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.c.ShardLeaderElectionContext
Enough replicas found to continue.
[junit4] 2> 587706 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.c.ShardLeaderElectionContext
I may be the new leader - try and sync
[junit4] 2> 587706 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.c.SyncStrategy Sync replicas
to https://127.0.0.1:33061/solr/acollectionafterbaddelete_shard1_replica1/
[junit4] 2> 587706 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.u.PeerSync PeerSync:
core=acollectionafterbaddelete_shard1_replica1 url=https://127.0.0.1:33061/solr
START
replicas=[https://127.0.0.1:39509/solr/acollectionafterbaddelete_shard1_replica2/]
nUpdates=100
[junit4] 2> 587708 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.u.PeerSync PeerSync:
core=acollectionafterbaddelete_shard1_replica1 url=https://127.0.0.1:33061/solr
DONE. We have no versions. sync failed.
[junit4] 2> 587714 INFO (qtp6200604-7743) [n:127.0.0.1:39509_solr
c:acollectionafterbaddelete s:shard1 r:core_node2
x:acollectionafterbaddelete_shard1_replica2] o.a.s.c.S.Request
[acollectionafterbaddelete_shard1_replica2] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 587714 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.c.SyncStrategy Leader's
attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 587714 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.c.ShardLeaderElectionContext
We failed sync, but we have no versions - we can't sync in that case - we were
active before, so become leader anyway
[junit4] 2> 587714 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.c.ShardLeaderElectionContext
Found all replicas participating in election, clear LIR
[junit4] 2> 587717 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.c.ShardLeaderElectionContext
I am the new leader:
https://127.0.0.1:33061/solr/acollectionafterbaddelete_shard1_replica1/ shard1
[junit4] 2> 587820 INFO
(zkCallback-850-thread-1-processing-n:127.0.0.1:39509_solr)
[n:127.0.0.1:39509_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/acollectionafterbaddelete/state.json] for collection
[acollectionafterbaddelete] has occurred - updating... (live nodes size: [4])
[junit4] 2> 587820 INFO
(zkCallback-851-thread-1-processing-n:127.0.0.1:33061_solr)
[n:127.0.0.1:33061_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/acollectionafterbaddelete/state.json] for collection
[acollectionafterbaddelete] has occurred - updating... (live nodes size: [4])
[junit4] 2> 587868 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.c.ZkController I am the
leader, no recovery necessary
[junit4] 2> 587872 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica1] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=acollectionafterbaddelete_shard1_replica1&action=CREATE&numShards=1&collection=acollectionafterbaddelete&shard=shard1&wt=javabin&version=2}
status=0 QTime=1309
[junit4] 2> 587975 INFO
(zkCallback-851-thread-1-processing-n:127.0.0.1:33061_solr)
[n:127.0.0.1:33061_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/acollectionafterbaddelete/state.json] for collection
[acollectionafterbaddelete] has occurred - updating... (live nodes size: [4])
[junit4] 2> 587976 INFO
(zkCallback-850-thread-1-processing-n:127.0.0.1:39509_solr)
[n:127.0.0.1:39509_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/acollectionafterbaddelete/state.json] for collection
[acollectionafterbaddelete] has occurred - updating... (live nodes size: [4])
[junit4] 2> 588709 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:acollectionafterbaddelete s:shard1
x:acollectionafterbaddelete_shard1_replica2] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=acollectionafterbaddelete_shard1_replica2&action=CREATE&numShards=1&collection=acollectionafterbaddelete&shard=shard1&wt=javabin&version=2}
status=0 QTime=2100
[junit4] 2> 588715 INFO (qtp3255001-7747) [n:127.0.0.1:33061_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> 588815 INFO
(zkCallback-851-thread-1-processing-n:127.0.0.1:33061_solr)
[n:127.0.0.1:33061_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/acollectionafterbaddelete/state.json] for collection
[acollectionafterbaddelete] has occurred - updating... (live nodes size: [4])
[junit4] 2> 588815 INFO
(zkCallback-850-thread-1-processing-n:127.0.0.1:39509_solr)
[n:127.0.0.1:39509_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/acollectionafterbaddelete/state.json] for collection
[acollectionafterbaddelete] has occurred - updating... (live nodes size: [4])
[junit4] 2> 589716 INFO (qtp3255001-7747) [n:127.0.0.1:33061_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=2&collection.configName=conf&name=acollectionafterbaddelete&action=CREATE&numShards=1&wt=javabin&version=2}
status=0 QTime=3276
[junit4] 2> 589719 INFO
(TEST-CollectionsAPIDistributedZkTest.testDeleteNonExistentCollection-seed#[2D297F6D227C8661])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testDeleteNonExistentCollection
[junit4] 2> 589767 INFO
(TEST-CollectionsAPIDistributedZkTest.deleteCollectionOnlyInZk-seed#[2D297F6D227C8661])
[ ] o.a.s.SolrTestCaseJ4 ###Starting deleteCollectionOnlyInZk
[junit4] 2> 589769 INFO
(TEST-CollectionsAPIDistributedZkTest.deleteCollectionOnlyInZk-seed#[2D297F6D227C8661])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 589774 INFO (qtp3255001-7750) [n:127.0.0.1:33061_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=acollectionafterbaddelete&action=DELETE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 589778 INFO
(OverseerCollectionConfigSetProcessor-98618525131603980-127.0.0.1:40977_solr-n_0000000000)
[n:127.0.0.1:40977_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> 589778 INFO
(OverseerThreadFactory-1722-thread-3-processing-n:127.0.0.1:40977_solr)
[n:127.0.0.1:40977_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 589791 INFO (qtp6200604-7733) [n:127.0.0.1:39509_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.acollectionafterbaddelete.shard1.replica2
[junit4] 2> 589791 INFO (qtp3255001-7753) [n:127.0.0.1:33061_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.acollectionafterbaddelete.shard1.replica1
[junit4] 2> 589792 INFO (qtp6200604-7733) [n:127.0.0.1:39509_solr ]
o.a.s.c.SolrCore [acollectionafterbaddelete_shard1_replica2] CLOSING SolrCore
org.apache.solr.core.SolrCore@19a84aa
[junit4] 2> 589792 INFO (qtp3255001-7753) [n:127.0.0.1:33061_solr ]
o.a.s.c.SolrCore [acollectionafterbaddelete_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@1e91d99
[junit4] 2> 589795 INFO (qtp6200604-7733) [n:127.0.0.1:39509_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.acollectionafterbaddelete.shard1.replica2
[junit4] 2> 589795 INFO (qtp3255001-7753) [n:127.0.0.1:33061_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.acollectionafterbaddelete.shard1.replica1
[junit4] 2> 589796 INFO (qtp6200604-7733) [n:127.0.0.1:39509_solr ]
o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader
parent node, won't remove previous leader registration.
[junit4] 2> 589796 WARN
(zkCallback-850-thread-1-processing-n:127.0.0.1:39509_solr)
[n:127.0.0.1:39509_solr ] o.a.s.c.LeaderElector Our node is no longer in
line to be leader
[junit4] 2> 589797 INFO (qtp6200604-7733) [n:127.0.0.1:39509_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={deleteInstanceDir=true&core=acollectionafterbaddelete_shard1_replica2&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=6
[junit4] 2> 589798 INFO (qtp3255001-7753) [n:127.0.0.1:33061_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={deleteInstanceDir=true&core=acollectionafterbaddelete_shard1_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=6
[junit4] 2> 590505 INFO (qtp3255001-7750) [n:127.0.0.1:33061_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=acollectionafterbaddelete&action=DELETE&wt=javabin&version=2}
status=0 QTime=731
[junit4] 2> 590511 INFO (qtp3255001-7732) [n:127.0.0.1:33061_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> 590515 INFO
(OverseerCollectionConfigSetProcessor-98618525131603980-127.0.0.1:40977_solr-n_0000000000)
[n:127.0.0.1:40977_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> 590519 INFO (qtp3255001-7732) [n:127.0.0.1:33061_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=onlyinzk&action=DELETE&wt=javabin&version=2} status=0 QTime=8
[junit4] 2> 590524 INFO (qtp3255001-7747) [n:127.0.0.1:33061_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 590525 INFO (qtp3255001-7747) [n:127.0.0.1:33061_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> 590528 INFO (qtp3255001-7753) [n:127.0.0.1:33061_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> 590532 INFO
(OverseerThreadFactory-1722-thread-5-processing-n:127.0.0.1:40977_solr)
[n:127.0.0.1:40977_solr ] o.a.s.c.CreateCollectionCmd Create collection
onlyinzk
[junit4] 2> 590532 INFO
(OverseerCollectionConfigSetProcessor-98618525131603980-127.0.0.1:40977_solr-n_0000000000)
[n:127.0.0.1:40977_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> 590744 INFO (qtp6200604-7725) [n:127.0.0.1:39509_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> 590851 INFO
(zkCallback-850-thread-1-processing-n:127.0.0.1:39509_solr)
[n:127.0.0.1:39509_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> 590866 INFO (qtp19255197-7757) [n:127.0.0.1:38591_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> 590866 INFO (qtp19255197-7757) [n:127.0.0.1:38591_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 590971 INFO
(zkCallback-850-thread-1-processing-n:127.0.0.1:39509_solr)
[n:127.0.0.1:39509_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> 590971 INFO
(zkCallback-852-thread-1-processing-n:127.0.0.1:38591_solr)
[n:127.0.0.1:38591_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> 591769 INFO (qtp6200604-7725) [n:127.0.0.1:39509_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.6.1
[junit4] 2> 591786 INFO (qtp6200604-7725) [n:127.0.0.1:39509_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.s.IndexSchema
[onlyinzk_shard2_replica1] Schema name=minimal
[junit4] 2> 591796 INFO (qtp6200604-7725) [n:127.0.0.1:39509_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> 591796 INFO (qtp6200604-7725) [n:127.0.0.1:39509_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> 591796 INFO (qtp6200604-7725) [n:127.0.0.1:39509_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 591797 INFO (qtp6200604-7725) [n:127.0.0.1:39509_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_2D297F6D227C8661-001/tempDir-001/node1/onlyinzk_shard2_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D297F6D227C8661-001/tempDir-001/node1/./onlyinzk_shard2_replica1/data/]
[junit4] 2> 591858 INFO (qtp6200604-7725) [n:127.0.0.1:39509_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 591858 INFO (qtp6200604-7725) [n:127.0.0.1:39509_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> 591862 INFO (qtp6200604-7725) [n:127.0.0.1:39509_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 591862 INFO (qtp6200604-7725) [n:127.0.0.1:39509_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 591863 INFO (qtp6200604-7725) [n:127.0.0.1:39509_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.s.SolrIndexSearcher
Opening [Searcher@685f0c[onlyinzk_shard2_replica1] main]
[junit4] 2> 591864 INFO (qtp6200604-7725) [n:127.0.0.1:39509_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 591865 INFO (qtp6200604-7725) [n:127.0.0.1:39509_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> 591865 INFO (qtp6200604-7725) [n:127.0.0.1:39509_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 591865 INFO
(searcherExecutor-1741-thread-1-processing-n:127.0.0.1:39509_solr
x:onlyinzk_shard2_replica1 s:shard2 c:onlyinzk) [n:127.0.0.1:39509_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.SolrCore
[onlyinzk_shard2_replica1] Registered new searcher
Searcher@685f0c[onlyinzk_shard2_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 591865 INFO (qtp6200604-7725) [n:127.0.0.1:39509_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 1577896408463179776
[junit4] 2> 591870 INFO (qtp6200604-7725) [n:127.0.0.1:39509_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 591870 INFO (qtp6200604-7725) [n:127.0.0.1:39509_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> 591870 INFO (qtp6200604-7725) [n:127.0.0.1:39509_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.SyncStrategy Sync
replicas to https://127.0.0.1:39509/solr/onlyinzk_shard2_replica1/
[junit4] 2> 591870 INFO (qtp6200604-7725) [n:127.0.0.1:39509_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.SyncStrategy Sync
Success - now sync replicas to me
[junit4] 2> 591870 INFO (qtp6200604-7725) [n:127.0.0.1:39509_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.SyncStrategy
https://127.0.0.1:39509/solr/onlyinzk_shard2_replica1/ has no replicas
[junit4] 2> 591870 INFO (qtp6200604-7725) [n:127.0.0.1:39509_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 591873 INFO (qtp6200604-7725) [n:127.0.0.1:39509_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:39509/solr/onlyinzk_shard2_replica1/ shard2
[junit4] 2> 591884 INFO (qtp19255197-7757) [n:127.0.0.1:38591_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.6.1
[junit4] 2> 591910 INFO (qtp19255197-7757) [n:127.0.0.1:38591_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.s.IndexSchema
[onlyinzk_shard1_replica1] Schema name=minimal
[junit4] 2> 591916 INFO (qtp19255197-7757) [n:127.0.0.1:38591_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> 591916 INFO (qtp19255197-7757) [n:127.0.0.1:38591_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> 591917 INFO (qtp19255197-7757) [n:127.0.0.1:38591_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 591917 INFO (qtp19255197-7757) [n:127.0.0.1:38591_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_2D297F6D227C8661-001/tempDir-001/node3/onlyinzk_shard1_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D297F6D227C8661-001/tempDir-001/node3/./onlyinzk_shard1_replica1/data/]
[junit4] 2> 591969 INFO (qtp19255197-7757) [n:127.0.0.1:38591_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 591969 INFO (qtp19255197-7757) [n:127.0.0.1:38591_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> 591970 INFO (qtp19255197-7757) [n:127.0.0.1:38591_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 591970 INFO (qtp19255197-7757) [n:127.0.0.1:38591_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 591971 INFO (qtp19255197-7757) [n:127.0.0.1:38591_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.s.SolrIndexSearcher
Opening [Searcher@33ef49[onlyinzk_shard1_replica1] main]
[junit4] 2> 591972 INFO (qtp19255197-7757) [n:127.0.0.1:38591_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 591972 INFO (qtp19255197-7757) [n:127.0.0.1:38591_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> 591972 INFO (qtp19255197-7757) [n:127.0.0.1:38591_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 591973 INFO
(searcherExecutor-1746-thread-1-processing-n:127.0.0.1:38591_solr
x:onlyinzk_shard1_replica1 s:shard1 c:onlyinzk) [n:127.0.0.1:38591_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.SolrCore
[onlyinzk_shard1_replica1] Registered new searcher
Searcher@33ef49[onlyinzk_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 591973 INFO (qtp19255197-7757) [n:127.0.0.1:38591_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 1577896408576425984
[junit4] 2> 591974 INFO
(zkCallback-850-thread-1-processing-n:127.0.0.1:39509_solr)
[n:127.0.0.1:39509_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> 591974 INFO
(zkCallback-852-thread-1-processing-n:127.0.0.1:38591_solr)
[n:127.0.0.1:38591_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> 591977 INFO (qtp19255197-7757) [n:127.0.0.1:38591_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 591977 INFO (qtp19255197-7757) [n:127.0.0.1:38591_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> 591977 INFO (qtp19255197-7757) [n:127.0.0.1:38591_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.SyncStrategy Sync
replicas to https://127.0.0.1:38591/solr/onlyinzk_shard1_replica1/
[junit4] 2> 591977 INFO (qtp19255197-7757) [n:127.0.0.1:38591_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.SyncStrategy Sync
Success - now sync replicas to me
[junit4] 2> 591977 INFO (qtp19255197-7757) [n:127.0.0.1:38591_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.SyncStrategy
https://127.0.0.1:38591/solr/onlyinzk_shard1_replica1/ has no replicas
[junit4] 2> 591977 INFO (qtp19255197-7757) [n:127.0.0.1:38591_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 591982 INFO (qtp19255197-7757) [n:127.0.0.1:38591_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:38591/solr/onlyinzk_shard1_replica1/ shard1
[junit4] 2> 592023 INFO (qtp6200604-7725) [n:127.0.0.1:39509_solr
c:onlyinzk s:shard2 x:onlyinzk_shard2_replica1] o.a.s.c.ZkController I am the
leader, no recovery necessary
[junit4] 2> 592025 INFO (qtp6200604-7725) [n:127.0.0.1:39509_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=1282
[junit4] 2> 592127 INFO
(zkCallback-852-thread-1-processing-n:127.0.0.1:38591_solr)
[n:127.0.0.1:38591_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> 592127 INFO
(zkCallback-850-thread-1-processing-n:127.0.0.1:39509_solr)
[n:127.0.0.1:39509_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> 592133 INFO (qtp19255197-7757) [n:127.0.0.1:38591_solr
c:onlyinzk s:shard1 x:onlyinzk_shard1_replica1] o.a.s.c.ZkController I am the
leader, no recovery necessary
[junit4] 2> 592134 INFO (qtp19255197-7757) [n:127.0.0.1:38591_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=1268
[junit4] 2> 592136 INFO (qtp3255001-7753) [n:127.0.0.1:33061_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> 592236 INFO
(zkCallback-850-thread-1-processing-n:127.0.0.1:39509_solr)
[n:127.0.0.1:39509_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> 592236 INFO
(zkCallback-852-thread-1-processing-n:127.0.0.1:38591_solr)
[n:127.0.0.1:38591_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> 592535 INFO
(OverseerCollectionConfigSetProcessor-98618525131603980-127.0.0.1:40977_solr-n_0000000000)
[n:127.0.0.1:40977_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> 593137 INFO (qtp3255001-7753) [n:127.0.0.1:33061_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=2609
[junit4] 2> 593141 INFO (qtp3255001-7750) [n:127.0.0.1:33061_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 593142 INFO (qtp3255001-7750) [n:127.0.0.1:33061_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> 593143 INFO
(TEST-CollectionsAPIDistributedZkTest.deleteCollectionOnlyInZk-seed#[2D297F6D227C8661])
[ ] o.a.s.SolrTestCaseJ4 ###Ending deleteCollectionOnlyInZk
[junit4] 2> 593181 INFO
(TEST-CollectionsAPIDistributedZkTest.testMaxNodesPerShard-seed#[2D297F6D227C8661])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testMaxNodesPerShard
[junit4] 2> 593182 INFO
(TEST-CollectionsAPIDistributedZkTest.testMaxNodesPerShard-seed#[2D297F6D227C8661])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 593186 INFO (qtp3255001-7732) [n:127.0.0.1:33061_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> 593189 INFO
(OverseerThreadFactory-1722-thread-5-processing-n:127.0.0.1:40977_solr)
[n:127.0.0.1:40977_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 593193 INFO (qtp6200604-7743) [n:127.0.0.1:39509_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.onlyinzk.shard2.replica1
[junit4] 2> 593193 INFO (qtp6200604-7743) [n:127.0.0.1:39509_solr ]
o.a.s.c.SolrCore [onlyinzk_shard2_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@1fc3fc5
[junit4] 2> 593193 INFO (qtp19255197-7722) [n:127.0.0.1:38591_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.onlyinzk.shard1.replica1
[junit4] 2> 593194 INFO (qtp19255197-7722) [n:127.0.0.1:38591_solr ]
o.a.s.c.SolrCore [onlyinzk_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@148bd91
[junit4] 2> 593203 INFO (qtp19255197-7722) [n:127.0.0.1:38591_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.onlyinzk.shard1.replica1
[junit4] 2> 593203 INFO (qtp6200604-7743) [n:127.0.0.1:39509_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.onlyinzk.shard2.replica1
[junit4] 2> 593206 INFO (qtp19255197-7722) [n:127.0.0.1:38591_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=13
[junit4] 2> 593206 INFO (qtp6200604-7743) [n:127.0.0.1:39509_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=14
[junit4] 2> 593912 INFO (qtp3255001-7732) [n:127.0.0.1:33061_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=onlyinzk&action=DELETE&wt=javabin&version=2} status=0 QTime=726
[junit4] 2> 593916 INFO (qtp3255001-7747) [n:127.0.0.1:33061_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=2&collection.configName=conf&name=oversharded&action=CREATE&numShards=3&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 593917 INFO
(OverseerThreadFactory-1722-thread-5-processing-n:127.0.0.1:40977_solr)
[n:127.0.0.1:40977_solr ] o.a.s.c.CreateCollectionCmd Create collection
oversharded
[junit4] 2> 593917 INFO
(OverseerCollectionConfigSetProcessor-98618525131603980-127.0.0.1:40977_solr-n_0000000000)
[n:127.0.0.1:40977_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> 593917 ERROR
(OverseerThreadFactory-1722-thread-5-processing-n:127.0.0.1:40977_solr)
[n:127.0.0.1:40977_solr ] o.a.s.c.OverseerCollectionMessageHandler
Collection: oversharded operation: create
failed:org.apache.solr.common.SolrException: Cannot create collection
oversharded. Value of maxShardsPerNode is 1, and the number of nodes currently
live or live and part of your createNodeSet is 4. This allows a maximum of 4 to
be created. Value of numShards is 3 and value of replicationFactor is 2. This
requires 6 shards to be created (higher than the allowed number)
[junit4] 2> at
org.apache.solr.cloud.CreateCollectionCmd.call(CreateCollectionCmd.java:155)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:226)
[junit4] 2> at
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:464)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 593920 INFO (qtp3255001-7747) [n:127.0.0.1:33061_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=2&collection.configName=conf&name=oversharded&action=CREATE&numShards=3&wt=javabin&version=2}
status=400 QTime=3
[junit4] 2> 593920 INFO
(TEST-CollectionsAPIDistributedZkTest.testMaxNodesPerShard-seed#[2D297F6D227C8661])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testMaxNodesPerShard
[junit4] 2> 593943 INFO
(TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[2D297F6D227C8661])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testTooManyReplicas
[junit4] 2> 593943 INFO
(TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[2D297F6D227C8661])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 593946 INFO (qtp3255001-7753) [n:127.0.0.1:33061_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> 593947 INFO
(OverseerThreadFactory-1722-thread-5-processing-n:127.0.0.1:40977_solr)
[n:127.0.0.1:40977_solr ] o.a.s.c.CreateCollectionCmd Create collection
collection
[junit4] 2> 593948 INFO
(OverseerCollectionConfigSetProcessor-98618525131603980-127.0.0.1:40977_solr-n_0000000000)
[n:127.0.0.1:40977_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> 593948 WARN
(OverseerThreadFactory-1722-thread-5-processing-n:127.0.0.1:40977_solr)
[n:127.0.0.1:40977_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> 593948 ERROR
(OverseerThreadFactory-1722-thread-5-processing-n:127.0.0.1:40977_solr)
[n:127.0.0.1:40977_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> 593950 INFO (qtp3255001-7753) [n:127.0.0.1:33061_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=3
[junit4] 2> 593950 INFO
(TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[2D297F6D227C8661])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testTooManyReplicas
[junit4] 2> 593970 INFO
(TEST-CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection-seed#[2D297F6D227C8661])
[ ] o.a.s.SolrTestCaseJ4 ###Starting deletePartiallyCreatedCollection
[junit4] 2> 593971 INFO
(TEST-CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection-seed#[2D297F6D227C8661])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 593975 INFO (qtp3255001-7750) [n:127.0.0.1:33061_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_2D297F6D227C8661-001/tempDir-002&collection.configName=conf&name=halfdeletedcollection_shard1_replica1&action=CREATE&numShards=2&collection=halfdeletedcollection&wt=javabin&version=2
[junit4] 2> 593976 INFO
(OverseerStateUpdate-98618525131603980-127.0.0.1:40977_solr-n_0000000000)
[n:127.0.0.1:40977_solr ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard2
[junit4] 2> 595016 INFO (qtp3255001-7750) [n:127.0.0.1:33061_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
[junit4] 2> 595039 INFO (qtp3255001-7750) [n:127.0.0.1:33061_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.s.IndexSchema [halfdeletedcollection_shard1_replica1] Schema name=minimal
[junit4] 2> 595046 INFO (qtp3255001-7750) [n:127.0.0.1:33061_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 595046 INFO (qtp3255001-7750) [n:127.0.0.1:33061_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> 595047 INFO (qtp3255001-7750) [n:127.0.0.1:33061_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 595047 INFO (qtp3255001-7750) [n:127.0.0.1:33061_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_2D297F6D227C8661-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_2D297F6D227C8661-001/tempDir-002/]
[junit4] 2> 595112 INFO (qtp3255001-7750) [n:127.0.0.1:33061_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 595112 INFO (qtp3255001-7750) [n:127.0.0.1:33061_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> 595113 INFO (qtp3255001-7750) [n:127.0.0.1:33061_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 595113 INFO (qtp3255001-7750) [n:127.0.0.1:33061_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 595114 INFO (qtp3255001-7750) [n:127.0.0.1:33061_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@a1ad0a[halfdeletedcollection_shard1_replica1] main]
[junit4] 2> 595115 INFO (qtp3255001-7750) [n:127.0.0.1:33061_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 595115 INFO (qtp3255001-7750) [n:127.0.0.1:33061_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> 595115 INFO (qtp3255001-7750) [n:127.0.0.1:33061_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 595117 INFO
(searcherExecutor-1751-thread-1-processing-n:127.0.0.1:33061_solr
x:halfdeletedcollection_shard1_replica1 c:halfdeletedcollection)
[n:127.0.0.1:33061_solr c:halfdeletedcollection
x:halfdeletedcollection_shard1_replica1] o.a.s.c.SolrCore
[halfdeletedcollection_shard1_replica1] Registered new searcher
Searcher@a1ad0a[halfdeletedcollection_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 595117 INFO (qtp3255001-7750) [n:127.0.0.1:33061_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 1577896411873148928
[junit4] 2> 595120 INFO (qtp3255001-7750) [n:127.0.0.1:33061_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 595120 INFO (qtp3255001-7750) [n:127.0.0.1:33061_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 595120 INFO (qtp3255001-7750) [n:127.0.0.1:33061_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:33061/solr/halfdeletedcollection_shard1_replica1/
[junit4] 2> 595121 INFO (qtp3255001-7750) [n:127.0.0.1:33061_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 595121 INFO (qtp3255001-7750) [n:127.0.0.1:33061_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SyncStrategy
https://127.0.0.1:33061/solr/halfdeletedcollection_shard1_replica1/ has no
replicas
[junit4] 2> 595121 INFO (qtp3255001-7750) [n:127.0.0.1:33061_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 595123 INFO (qtp3255001-7750) [n:127.0.0.1:33061_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:33061/solr/halfdeletedcollection_shard1_replica1/ shard2
[junit4] 2> 595273 INFO (qtp3255001-7750) [n:127.0.0.1:33061_solr
c:halfdeletedcollection x:halfdeletedcollection_shard1_replica1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 595274 INFO (qtp3255001-7750) [n:127.0.0.1:33061_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_2D297F6D227C8661-001/tempDir-002&collection.configName=conf&name=halfdeletedcollection_shard1_replica1&action=CREATE&numShards=2&collection=halfdeletedcollection&wt=javabin&version=2}
status=0 QTime=1300
[junit4] 2> 595277 INFO (qtp3255001-7732) [n:127.0.0.1:33061_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> 595279 INFO
(OverseerThreadFactory-1722-thread-5-processing-n:127.0.0.1:40977_solr)
[n:127.0.0.1:40977_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 595281 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.halfdeletedcollection.shard1.replica1
[junit4] 2> 595281 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr ]
o.a.s.c.SolrCore [halfdeletedcollection_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@29d4f
[junit4] 2> 595284 INFO (qtp3255001-7728) [n:127.0.0.1:33061_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.halfdeletedcollection.shard1.replica1
[junit4] 2> 595285 INFO (qtp3255001-7728) [n:127.0.0.1:33061_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=4
[junit4] 2> 595989 INFO (qtp3255001-7732) [n:127.0.0.1:33061_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=halfdeletedcollection&action=DELETE&wt=javabin&version=2} status=0
QTime=711
[junit4] 2> 595991 INFO (qtp3255001-7747) [n:127.0.0.1:33061_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 595991 INFO (qtp3255001-7747) [n:127.0.0.1:33061_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> 595993 INFO (qtp3255001-7750) [n:127.0.0.1:33061_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> 595995 INFO
(OverseerThreadFactory-1722-thread-5-processing-n:127.0.0.1:40977_solr)
[n:127.0.0.1:40977_solr ] o.a.s.c.CreateCollectionCmd Create collection
halfdeletedcollection
[junit4] 2> 595995 INFO
(OverseerCollectionConfigSetProcessor-98618525131603980-127.0.0.1:40977_solr-n_0000000000)
[n:127.0.0.1:40977_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> 596208 INFO (qtp6200604-7723) [n:127.0.0.1:39509_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> 596208 INFO (qtp19255197-7748) [n:127.0.0.1:38591_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> 596316 INFO
(zkCallback-852-thread-2-processing-n:127.0.0.1:38591_solr)
[n:127.0.0.1:38591_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> 596316 INFO
(zkCallback-850-thread-2-processing-n:127.0.0.1:39509_solr)
[n:127.0.0.1:39509_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> 597242 INFO (qtp19255197-7748) [n:127.0.0.1:38591_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
[junit4] 2> 597243 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
[junit4] 2> 597278 INFO (qtp19255197-7748) [n:127.0.0.1:38591_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.s.IndexSchema [halfdeletedcollection_shard2_replica1] Schema name=minimal
[junit4] 2> 597278 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.s.IndexSchema [halfdeletedcollection_shard1_replica1] Schema name=minimal
[junit4] 2> 597287 INFO (qtp6200604-7723) [n:127.0.0.1:39509_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> 597287 INFO (qtp19255197-7748) [n:127.0.0.1:38591_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> 597287 INFO (qtp6200604-7723) [n:127.0.0.1:39509_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> 597287 INFO (qtp19255197-7748) [n:127.0.0.1:38591_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> 597287 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 597287 INFO (qtp19255197-7748) [n:127.0.0.1:38591_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 597287 INFO (qtp6200604-7723) [n:127.0.0.1:39509_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_2D297F6D227C8661-001/tempDir-001/node1/halfdeletedcollection_shard1_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D297F6D227C8661-001/tempDir-001/node1/./halfdeletedcollection_shard1_replica1/data/]
[junit4] 2> 597287 INFO (qtp19255197-7748) [n:127.0.0.1:38591_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_2D297F6D227C8661-001/tempDir-001/node3/halfdeletedcollection_shard2_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2D297F6D227C8661-001/tempDir-001/node3/./halfdeletedcollection_shard2_replica1/data/]
[junit4] 2> 597336 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 597337 INFO (qtp6200604-7723) [n:127.0.0.1:39509_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> 597336 INFO (qtp19255197-7748) [n:127.0.0.1:38591_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 597337 INFO (qtp19255197-7748) [n:127.0.0.1:38591_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> 597337 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 597337 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 597337 INFO (qtp19255197-7748) [n:127.0.0.1:38591_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 597337 INFO (qtp19255197-7748) [n:127.0.0.1:38591_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 597338 INFO (qtp19255197-7748) [n:127.0.0.1:38591_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1d5dda3[halfdeletedcollection_shard2_replica1] main]
[junit4] 2> 597338 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@141572f[halfdeletedcollection_shard1_replica1] main]
[junit4] 2> 597339 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 597339 INFO (qtp19255197-7748) [n:127.0.0.1:38591_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 597339 INFO (qtp6200604-7723) [n:127.0.0.1:39509_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> 597339 INFO (qtp19255197-7748) [n:127.0.0.1:38591_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> 597339 INFO (qtp6200604-7723) [n:127.0.0.1:39509_solr
c:halfdeletedcollection s:shard1 x:halfdeletedcollection_shard1_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 597339 INFO (qtp19255197-7748) [n:127.0.0.1:38591_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 597340 INFO (qtp19255197-7748) [n:127.0.0.1:38591_solr
c:halfdeletedcollection s:shard2 x:halfdeletedcollection_shard2_repli
[...truncated too long message...]
etty-closer-832-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.node
[junit4] 2> 675630 INFO (jetty-closer-832-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 675630 INFO (jetty-closer-832-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
[junit4] 2> 675630 INFO (jetty-closer-832-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 675630 INFO (jetty-closer-832-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
[junit4] 2> 675630 INFO (jetty-closer-832-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
[junit4] 2> 675630 INFO (jetty-closer-832-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
[junit4] 2> 675630 INFO (jetty-closer-832-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
[junit4] 2> 675630 INFO (jetty-closer-832-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
[junit4] 2> 675631 INFO (jetty-closer-832-thread-4) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@35ac17{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 675631 INFO (jetty-closer-832-thread-4) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=17509753
[junit4] 2> 675631 INFO (jetty-closer-832-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 675631 INFO (jetty-closer-832-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
[junit4] 2> 675631 INFO (jetty-closer-832-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
[junit4] 2> 675637 INFO (coreCloseExecutor-2030-thread-1)
[n:127.0.0.1:38591_solr c:halfcollectionblocker2 s:shard1 r:core_node1
x:halfcollection_shard1_replica1] o.a.s.c.SolrCore
[halfcollection_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@16db41b
[junit4] 2> 675637 INFO (coreCloseExecutor-2031-thread-1)
[n:127.0.0.1:39509_solr c:halfcollectionblocker s:shard1 r:core_node1
x:halfcollection_shard1_replica1] o.a.s.c.SolrCore
[halfcollection_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@b827ff
[junit4] 2> 675639 INFO (coreCloseExecutor-2031-thread-1)
[n:127.0.0.1:39509_solr c:halfcollectionblocker s:shard1 r:core_node1
x:halfcollection_shard1_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.halfcollection_shard1_replica1
[junit4] 2> 675639 WARN
(zkCallback-885-thread-4-processing-n:127.0.0.1:40977_solr)
[n:127.0.0.1:40977_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 675639 INFO
(zkCallback-850-thread-4-processing-n:127.0.0.1:39509_solr)
[n:127.0.0.1:39509_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 675639 INFO (coreCloseExecutor-2030-thread-1)
[n:127.0.0.1:38591_solr c:halfcollectionblocker2 s:shard1 r:core_node1
x:halfcollection_shard1_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.halfcollection_shard1_replica1
[junit4] 2> 675640 INFO
(zkCallback-852-thread-4-processing-n:127.0.0.1:38591_solr)
[n:127.0.0.1:38591_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 675640 INFO
(zkCallback-852-thread-4-processing-n:127.0.0.1:38591_solr)
[n:127.0.0.1:38591_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 675640 INFO
(zkCallback-851-thread-4-processing-n:127.0.0.1:33061_solr)
[n:127.0.0.1:33061_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (2)
[junit4] 2> 675641 WARN
(zkCallback-852-thread-4-processing-n:127.0.0.1:38591_solr)
[n:127.0.0.1:38591_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 675641 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:35477) [
] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15e5d06aebe000b, 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> 675641 INFO
(zkCallback-851-thread-2-processing-n:127.0.0.1:33061_solr)
[n:127.0.0.1:33061_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 675641 INFO (jetty-closer-832-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@589bb1{/solr,null,UNAVAILABLE}
[junit4] 2> 675642 INFO (jetty-closer-832-thread-4) [ ]
o.a.s.c.Overseer Overseer
(id=98618525131603978-127.0.0.1:33061_solr-n_0000000001) closing
[junit4] 2> 675642 INFO
(OverseerStateUpdate-98618525131603978-127.0.0.1:33061_solr-n_0000000001)
[n:127.0.0.1:33061_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:33061_solr
[junit4] 2> 675645 WARN
(zkCallback-851-thread-2-processing-n:127.0.0.1:33061_solr)
[n:127.0.0.1:33061_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 675645 INFO (jetty-closer-832-thread-4) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1ac126a{/solr,null,UNAVAILABLE}
[junit4] 2> 677140 WARN
(zkCallback-850-thread-4-processing-n:127.0.0.1:39509_solr)
[n:127.0.0.1:39509_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 677140 INFO (jetty-closer-832-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1ea4193{/solr,null,UNAVAILABLE}
[junit4] 2> 677141 WARN
(zkCallback-852-thread-6-processing-n:127.0.0.1:38591_solr)
[n:127.0.0.1:38591_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for
/clusterstate.json]
[junit4] 2> 677141 INFO (jetty-closer-832-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1b87e77{/solr,null,UNAVAILABLE}
[junit4] 2> 677141 ERROR
(SUITE-CollectionsAPIDistributedZkTest-seed#[2D297F6D227C8661]-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> 677142 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[2D297F6D227C8661]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:35477 35477
[junit4] 2> 680807 INFO (Thread-4244) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:35477 35477
[junit4] 2> 680809 WARN (Thread-4244) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/configs/conf
[junit4] 2> 4 /solr/aliases.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 220 /solr/clusterstate.json
[junit4] 2> 4
/solr/collections/nodes_used_collection/state.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 216 /solr/collections
[junit4] 2> 4 /solr/live_nodes
[junit4] 2>
[junit4] 2> 680810 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[2D297F6D227C8661]-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_2D297F6D227C8661-001
[junit4] 2> NOTE: test params are: codec=CheapBastard,
sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=zh-TW,
timezone=America/Hermosillo
[junit4] 2> NOTE: Linux 4.10.0-33-generic i386/Oracle Corporation
1.8.0_144 (32-bit)/cpus=8,threads=1,free=83119896,total=199467008
[junit4] 2> NOTE: All tests run in this JVM: [CheckHdfsIndexTest,
TestSmileRequest, TestRawResponseWriter, HdfsRecoveryZkTest,
SaslZkACLProviderTest, TestCollapseQParserPlugin,
TestSolrQueryParserDefaultOperatorResource, TestShardHandlerFactory,
TestNumericTerms64, SolrJmxReporterCloudTest,
DistributedQueryComponentOptimizationTest,
BigEndianAscendingWordSerializerTest, HdfsDirectoryFactoryTest,
BasicFunctionalityTest, TestFuzzyAnalyzedSuggestions, TestDistribIDF,
ClassificationUpdateProcessorFactoryTest, TestCustomSort, DocumentBuilderTest,
TestJsonFacetRefinement, TestCollationFieldDocValues, TestSegmentSorting,
SearchHandlerTest, TestExclusionRuleCollectionAccess,
LeaderFailoverAfterPartitionTest, TestCoreContainer,
SolrMetricsIntegrationTest, TestDynamicLoading, CdcrRequestHandlerTest,
UUIDUpdateProcessorFallbackTest, TestSolrDynamicMBean,
TestFieldCacheWithThreads, IndexSchemaTest, TestCloudDeleteByQuery,
TestElisionMultitermQuery, TestPayloadScoreQParserPlugin,
TestPartialUpdateDeduplication, TestSubQueryTransformerDistrib, JsonLoaderTest,
TestLMDirichletSimilarityFactory, ZkSolrClientTest, HdfsBasicDistributedZkTest,
SuggesterFSTTest, SmileWriterTest, SchemaVersionSpecificBehaviorTest,
BlockJoinFacetDistribTest, ClusterStateUpdateTest, TestSolrCoreSnapshots,
IndexSchemaRuntimeFieldTest, CollectionsAPIDistributedZkTest]
[junit4] Completed [144/713 (1!)] on J1 in 95.04s, 20 tests, 1 failure <<<
FAILURES!
[...truncated 41372 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
