Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/260/
1 tests failed.
FAILED:
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.deleteCollectionOnlyInZk
Error Message:
KeeperErrorCode = Session expired for /clusterstate.json
Stack Trace:
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode =
Session expired for /clusterstate.json
at
__randomizedtesting.SeedInfo.seed([C43CE26D93BF099C:70DCBEEC65B9A98D]:0)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1102)
at
org.apache.solr.common.cloud.SolrZkClient.lambda$exists$3(SolrZkClient.java:308)
at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
at
org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:308)
at
org.apache.solr.common.cloud.ZkStateReader.createClusterStateWatchersAndUpdate(ZkStateReader.java:421)
at
org.apache.solr.cloud.MiniSolrCloudCluster.deleteAllCollections(MiniSolrCloudCluster.java:442)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.clearCluster(CollectionsAPIDistributedZkTest.java:111)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:968)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 11804 lines...]
[junit4] JVM J0: stdout was not empty, see:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/temp/junit4-J0-20171127_130246_6522659386859843272668.sysout
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] #
[junit4] # There is insufficient memory for the Java Runtime Environment to
continue.
[junit4] # Native memory allocation (mmap) failed to map 22020096 bytes for
committing reserved memory.
[junit4] # An error report file with more information is saved as:
[junit4] #
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/hs_err_pid8603.log
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J0: stderr was not empty, see:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/temp/junit4-J0-20171127_130246_6523585915014860266223.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: INFO:
os::commit_memory(0x00000000eed80000, 22020096, 0) failed; error='Cannot
allocate memory' (errno=12)
[junit4] <<< JVM J0: EOF ----
[...truncated 159 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_C43CE26D93BF099C-001/init-core-data-001
[junit4] 2> 1420139 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[C43CE26D93BF099C]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1420154 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[C43CE26D93BF099C]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1420155 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[C43CE26D93BF099C]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1420156 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[C43CE26D93BF099C]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_C43CE26D93BF099C-001/tempDir-001
[junit4] 2> 1420156 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[C43CE26D93BF099C]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1420170 INFO (Thread-1968) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1420170 INFO (Thread-1968) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1420329 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[C43CE26D93BF099C]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:44290
[junit4] 2> 1421010 ERROR (Thread-1968) [ ] 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> 1421179 INFO (jetty-launcher-484-thread-1) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1421180 INFO (jetty-launcher-484-thread-2) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1421183 INFO (jetty-launcher-484-thread-3) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1421187 INFO (jetty-launcher-484-thread-4) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1421187 INFO (jetty-launcher-484-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@d609148{/solr,null,AVAILABLE}
[junit4] 2> 1421195 INFO (jetty-launcher-484-thread-3) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@55d2cd29{SSL,[ssl,
http/1.1]}{127.0.0.1:42635}
[junit4] 2> 1421195 INFO (jetty-launcher-484-thread-3) [ ]
o.e.j.s.Server Started @1441969ms
[junit4] 2> 1421195 INFO (jetty-launcher-484-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=42635}
[junit4] 2> 1421195 ERROR (jetty-launcher-484-thread-3) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1421195 INFO (jetty-launcher-484-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.2.0
[junit4] 2> 1421195 INFO (jetty-launcher-484-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1421195 INFO (jetty-launcher-484-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1421195 INFO (jetty-launcher-484-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-27T13:26:48.959Z
[junit4] 2> 1421259 INFO (jetty-launcher-484-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@73dea631{/solr,null,AVAILABLE}
[junit4] 2> 1421259 INFO (jetty-launcher-484-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@1f488f3{SSL,[ssl,
http/1.1]}{127.0.0.1:40692}
[junit4] 2> 1421259 INFO (jetty-launcher-484-thread-2) [ ]
o.e.j.s.Server Started @1442033ms
[junit4] 2> 1421259 INFO (jetty-launcher-484-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=40692}
[junit4] 2> 1421260 ERROR (jetty-launcher-484-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1421260 INFO (jetty-launcher-484-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.2.0
[junit4] 2> 1421260 INFO (jetty-launcher-484-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1421260 INFO (jetty-launcher-484-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1421260 INFO (jetty-launcher-484-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-27T13:26:49.024Z
[junit4] 2> 1421420 INFO (jetty-launcher-484-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1d28dbf0{/solr,null,AVAILABLE}
[junit4] 2> 1421421 INFO (jetty-launcher-484-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@4f525d30{SSL,[ssl,
http/1.1]}{127.0.0.1:51479}
[junit4] 2> 1421421 INFO (jetty-launcher-484-thread-1) [ ]
o.e.j.s.Server Started @1442195ms
[junit4] 2> 1421421 INFO (jetty-launcher-484-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=51479}
[junit4] 2> 1421421 ERROR (jetty-launcher-484-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1421422 INFO (jetty-launcher-484-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.2.0
[junit4] 2> 1421422 INFO (jetty-launcher-484-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1421422 INFO (jetty-launcher-484-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1421422 INFO (jetty-launcher-484-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-27T13:26:49.186Z
[junit4] 2> 1421440 INFO (jetty-launcher-484-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1421457 INFO (jetty-launcher-484-thread-2) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 1421458 INFO (jetty-launcher-484-thread-2) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1421503 INFO (jetty-launcher-484-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@5446c725{/solr,null,AVAILABLE}
[junit4] 2> 1421504 INFO (jetty-launcher-484-thread-4) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@364c210d{SSL,[ssl,
http/1.1]}{127.0.0.1:57790}
[junit4] 2> 1421504 INFO (jetty-launcher-484-thread-4) [ ]
o.e.j.s.Server Started @1442278ms
[junit4] 2> 1421504 INFO (jetty-launcher-484-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=57790}
[junit4] 2> 1421504 ERROR (jetty-launcher-484-thread-4) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1421504 INFO (jetty-launcher-484-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.2.0
[junit4] 2> 1421504 INFO (jetty-launcher-484-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1421504 INFO (jetty-launcher-484-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1421504 INFO (jetty-launcher-484-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-27T13:26:49.268Z
[junit4] 2> 1421635 INFO (jetty-launcher-484-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1421637 INFO (jetty-launcher-484-thread-4) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 1421637 INFO (jetty-launcher-484-thread-4) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1421658 INFO (jetty-launcher-484-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1421887 INFO (jetty-launcher-484-thread-3) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 1421887 INFO (jetty-launcher-484-thread-3) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1421947 INFO (jetty-launcher-484-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44290/solr
[junit4] 2> 1422752 INFO (jetty-launcher-484-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44290/solr
[junit4] 2> 1422946 INFO (jetty-launcher-484-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44290/solr
[junit4] 2> 1422754 INFO (jetty-launcher-484-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1423070 INFO (jetty-launcher-484-thread-1) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 1423070 INFO (jetty-launcher-484-thread-1) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1423123 INFO (jetty-launcher-484-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44290/solr
[junit4] 2> 1423377 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15ffda8a21d0007, 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> 1423382 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15ffda8a21d0008, 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> 1423384 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15ffda8a21d0009, 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> 1423450 INFO (jetty-launcher-484-thread-3)
[n:127.0.0.1:42635_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1423451 INFO (jetty-launcher-484-thread-3)
[n:127.0.0.1:42635_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:42635_solr
[junit4] 2> 1423452 INFO (jetty-launcher-484-thread-3)
[n:127.0.0.1:42635_solr ] o.a.s.c.Overseer Overseer
(id=99076617541582858-127.0.0.1:42635_solr-n_0000000000) starting
[junit4] 2> 1423572 INFO (jetty-launcher-484-thread-4)
[n:127.0.0.1:57790_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1423598 INFO (jetty-launcher-484-thread-4)
[n:127.0.0.1:57790_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:57790_solr
[junit4] 2> 1423660 INFO (jetty-launcher-484-thread-1)
[n:127.0.0.1:51479_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1423661 INFO (jetty-launcher-484-thread-1)
[n:127.0.0.1:51479_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1423663 INFO (jetty-launcher-484-thread-1)
[n:127.0.0.1:51479_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:51479_solr
[junit4] 2> 1423690 INFO (jetty-launcher-484-thread-3)
[n:127.0.0.1:42635_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42635_solr
[junit4] 2> 1423714 INFO
(OverseerStateUpdate-99076617541582858-127.0.0.1:42635_solr-n_0000000000)
[n:127.0.0.1:42635_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1424054 INFO (jetty-launcher-484-thread-2)
[n:127.0.0.1:40692_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 1424067 INFO (jetty-launcher-484-thread-2)
[n:127.0.0.1:40692_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1424082 INFO (jetty-launcher-484-thread-2)
[n:127.0.0.1:40692_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40692_solr
[junit4] 2> 1424712 INFO (jetty-launcher-484-thread-1)
[n:127.0.0.1:51479_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ddcdf76
[junit4] 2> 1424734 INFO (jetty-launcher-484-thread-1)
[n:127.0.0.1:51479_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ddcdf76
[junit4] 2> 1424734 INFO (jetty-launcher-484-thread-1)
[n:127.0.0.1:51479_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ddcdf76
[junit4] 2> 1424751 INFO (jetty-launcher-484-thread-1)
[n:127.0.0.1:51479_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_C43CE26D93BF099C-001/tempDir-001/node1
[junit4] 2> 1424823 INFO
(zkCallback-505-thread-1-processing-n:127.0.0.1:57790_solr)
[n:127.0.0.1:57790_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 1424903 INFO
(zkCallback-504-thread-2-processing-n:127.0.0.1:42635_solr)
[n:127.0.0.1:42635_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (4)
[junit4] 2> 1424903 INFO
(zkCallback-505-thread-2-processing-n:127.0.0.1:57790_solr)
[n:127.0.0.1:57790_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1424907 INFO (jetty-launcher-484-thread-2)
[n:127.0.0.1:40692_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ddcdf76
[junit4] 2> 1424955 INFO
(zkCallback-494-thread-1-processing-n:127.0.0.1:40692_solr)
[n:127.0.0.1:40692_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1424955 INFO
(zkCallback-507-thread-1-processing-n:127.0.0.1:51479_solr)
[n:127.0.0.1:51479_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (4)
[junit4] 2> 1425060 INFO (jetty-launcher-484-thread-2)
[n:127.0.0.1:40692_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ddcdf76
[junit4] 2> 1425060 INFO (jetty-launcher-484-thread-2)
[n:127.0.0.1:40692_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ddcdf76
[junit4] 2> 1425061 INFO (jetty-launcher-484-thread-2)
[n:127.0.0.1:40692_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_C43CE26D93BF099C-001/tempDir-001/node2
[junit4] 2> 1425575 INFO (jetty-launcher-484-thread-3)
[n:127.0.0.1:42635_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ddcdf76
[junit4] 2> 1425596 INFO (jetty-launcher-484-thread-4)
[n:127.0.0.1:57790_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ddcdf76
[junit4] 2> 1425599 INFO (jetty-launcher-484-thread-3)
[n:127.0.0.1:42635_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ddcdf76
[junit4] 2> 1425599 INFO (jetty-launcher-484-thread-3)
[n:127.0.0.1:42635_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ddcdf76
[junit4] 2> 1425601 INFO (jetty-launcher-484-thread-3)
[n:127.0.0.1:42635_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_C43CE26D93BF099C-001/tempDir-001/node3
[junit4] 2> 1425616 INFO (jetty-launcher-484-thread-4)
[n:127.0.0.1:57790_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ddcdf76
[junit4] 2> 1425616 INFO (jetty-launcher-484-thread-4)
[n:127.0.0.1:57790_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ddcdf76
[junit4] 2> 1425617 INFO (jetty-launcher-484-thread-4)
[n:127.0.0.1:57790_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_C43CE26D93BF099C-001/tempDir-001/node4
[junit4] 2> 1426693 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[C43CE26D93BF099C]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1426762 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[C43CE26D93BF099C]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44290/solr ready
[junit4] 2> 1427670 INFO
(TEST-CollectionsAPIDistributedZkTest.deleteCollectionRemovesStaleZkCollectionsNode-seed#[C43CE26D93BF099C])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
deleteCollectionRemovesStaleZkCollectionsNode
[junit4] 2> 1427672 INFO
(TEST-CollectionsAPIDistributedZkTest.deleteCollectionRemovesStaleZkCollectionsNode-seed#[C43CE26D93BF099C])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1427752 INFO (qtp1778879221-4390) [n:127.0.0.1:51479_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=out_of_sync_collection&action=DELETE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 1427765 INFO (qtp1778879221-4390) [n:127.0.0.1:51479_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=out_of_sync_collection&action=DELETE&wt=javabin&version=2}
status=0 QTime=12
[junit4] 2> 1427771 INFO
(OverseerCollectionConfigSetProcessor-99076617541582858-127.0.0.1:42635_solr-n_0000000000)
[n:127.0.0.1:42635_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> 1427784 INFO (qtp1778879221-4391) [n:127.0.0.1:51479_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1427784 INFO (qtp1778879221-4391) [n:127.0.0.1:51479_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> 1427787 INFO
(TEST-CollectionsAPIDistributedZkTest.deleteCollectionRemovesStaleZkCollectionsNode-seed#[C43CE26D93BF099C])
[ ] o.a.s.SolrTestCaseJ4 ###Ending
deleteCollectionRemovesStaleZkCollectionsNode
[junit4] 2> 1427833 INFO
(TEST-CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection-seed#[C43CE26D93BF099C])
[ ] o.a.s.SolrTestCaseJ4 ###Starting deletePartiallyCreatedCollection
[junit4] 2> 1427834 INFO
(TEST-CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection-seed#[C43CE26D93BF099C])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1427845 INFO (qtp1778879221-4392) [n:127.0.0.1:51479_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf&name=halfdeletedcollection&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1428483 INFO
(OverseerThreadFactory-1107-thread-2-processing-n:127.0.0.1:42635_solr)
[n:127.0.0.1:42635_solr ] o.a.s.c.CreateCollectionCmd Create collection
halfdeletedcollection
[junit4] 2> 1428498 WARN
(OverseerThreadFactory-1107-thread-2-processing-n:127.0.0.1:42635_solr)
[n:127.0.0.1:42635_solr ] o.a.s.c.CreateCollectionCmd It is unusual to
create a collection (halfdeletedcollection) without cores.
[junit4] 2> 1429185 INFO (qtp1778879221-4392) [n:127.0.0.1:51479_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> 1429185 INFO (qtp1778879221-4392) [n:127.0.0.1:51479_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf&name=halfdeletedcollection&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&wt=javabin&version=2}
status=0 QTime=1340
[junit4] 2> 1429186 INFO (qtp1778879221-4393) [n:127.0.0.1:51479_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with
params
dataDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_C43CE26D93BF099C-001/tempDir-002&action=ADDREPLICA&collection=halfdeletedcollection&shard=shard1&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1429223 INFO
(OverseerCollectionConfigSetProcessor-99076617541582858-127.0.0.1:42635_solr-n_0000000000)
[n:127.0.0.1:42635_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> 1429224 INFO
(OverseerThreadFactory-1107-thread-3-processing-n:127.0.0.1:42635_solr)
[n:127.0.0.1:42635_solr ] o.a.s.c.AddReplicaCmd Node Identified
127.0.0.1:40692_solr for creating new replica
[junit4] 2> 1429304 INFO
(OverseerStateUpdate-99076617541582858-127.0.0.1:42635_solr-n_0000000000)
[n:127.0.0.1:42635_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"addreplica",
[junit4] 2> "collection":"halfdeletedcollection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"halfdeletedcollection_shard1_replica_n41",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:40692/solr";,
[junit4] 2> "node_name":"127.0.0.1:40692_solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 1430415 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node42&dataDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_C43CE26D93BF099C-001/tempDir-002&collection.configName=conf&name=halfdeletedcollection_shard1_replica_n41&action=CREATE&collection=halfdeletedcollection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1430416 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 1430550 INFO
(zkCallback-494-thread-1-processing-n:127.0.0.1:40692_solr)
[n:127.0.0.1:40692_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> 1430553 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.2.0
[junit4] 2> 1430575 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.s.IndexSchema
[halfdeletedcollection_shard1_replica_n41] Schema name=minimal
[junit4] 2> 1430577 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 1430577 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.CoreContainer Creating
SolrCore 'halfdeletedcollection_shard1_replica_n41' using configuration from
collection halfdeletedcollection, trusted=true
[junit4] 2> 1430578 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr.core.halfdeletedcollection.shard1.replica_n41' (registry
'solr.core.halfdeletedcollection.shard1.replica_n41') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ddcdf76
[junit4] 2> 1430578 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1430578 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.SolrCore
[[halfdeletedcollection_shard1_replica_n41] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_C43CE26D93BF099C-001/tempDir-001/node2/halfdeletedcollection_shard1_replica_n41],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_C43CE26D93BF099C-001/tempDir-002/]
[junit4] 2> 1430610 INFO
(zkCallback-494-thread-2-processing-n:127.0.0.1:40692_solr)
[n:127.0.0.1:40692_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> 1431006 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1431006 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1431007 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 1431007 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 1431039 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.s.SolrIndexSearcher Opening
[Searcher@4e2b465[halfdeletedcollection_shard1_replica_n41] main]
[junit4] 2> 1431041 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1431041 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1431042 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000ms.
[junit4] 2> 1431042 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.u.UpdateLog Could not find
max version in index or recent updates, using new clock 1585225891898720256
[junit4] 2> 1431088 INFO
(searcherExecutor-1116-thread-1-processing-n:127.0.0.1:40692_solr
x:halfdeletedcollection_shard1_replica_n41 s:shard1 c:halfdeletedcollection
r:core_node42) [n:127.0.0.1:40692_solr c:halfdeletedcollection s:shard1
r:core_node42 x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.SolrCore
[halfdeletedcollection_shard1_replica_n41] Registered new searcher
Searcher@4e2b465[halfdeletedcollection_shard1_replica_n41]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1431091 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.ShardLeaderElectionContext
Enough replicas found to continue.
[junit4] 2> 1431091 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.ShardLeaderElectionContext
I may be the new leader - try and sync
[junit4] 2> 1431091 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.SyncStrategy Sync replicas
to https://127.0.0.1:40692/solr/halfdeletedcollection_shard1_replica_n41/
[junit4] 2> 1431091 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.SyncStrategy Sync Success -
now sync replicas to me
[junit4] 2> 1431091 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.SyncStrategy
https://127.0.0.1:40692/solr/halfdeletedcollection_shard1_replica_n41/ has no
replicas
[junit4] 2> 1431091 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.ShardLeaderElectionContext
Found all replicas participating in election, clear LIR
[junit4] 2> 1431116 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.ShardLeaderElectionContext
I am the new leader:
https://127.0.0.1:40692/solr/halfdeletedcollection_shard1_replica_n41/ shard1
[junit4] 2> 1431230 INFO
(zkCallback-494-thread-2-processing-n:127.0.0.1:40692_solr)
[n:127.0.0.1:40692_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> 1431231 INFO
(zkCallback-494-thread-1-processing-n:127.0.0.1:40692_solr)
[n:127.0.0.1:40692_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> 1431323 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.c.ZkController I am the
leader, no recovery necessary
[junit4] 2> 1431325 INFO (qtp1447240445-4380) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node42
x:halfdeletedcollection_shard1_replica_n41] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node42&dataDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_C43CE26D93BF099C-001/tempDir-002&collection.configName=conf&name=halfdeletedcollection_shard1_replica_n41&action=CREATE&collection=halfdeletedcollection&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=909
[junit4] 2> 1431343 INFO (qtp1778879221-4393) [n:127.0.0.1:51479_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={dataDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_C43CE26D93BF099C-001/tempDir-002&action=ADDREPLICA&collection=halfdeletedcollection&shard=shard1&type=NRT&wt=javabin&version=2}
status=0 QTime=2157
[junit4] 2> 1431344 INFO (qtp1778879221-4386) [n:127.0.0.1:51479_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> 1431351 INFO
(OverseerCollectionConfigSetProcessor-99076617541582858-127.0.0.1:42635_solr-n_0000000000)
[n:127.0.0.1:42635_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> 1431364 INFO
(OverseerThreadFactory-1107-thread-4-processing-n:127.0.0.1:42635_solr)
[n:127.0.0.1:42635_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 1431373 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.halfdeletedcollection.shard1.replica_n41, tag=null
[junit4] 2> 1431373 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@8f4bb0a: rootName = null,
domain = solr.core.halfdeletedcollection.shard1.replica_n41, service url =
null, agent id = null] for registry
solr.core.halfdeletedcollection.shard1.replica_n41 /
com.codahale.metrics.MetricRegistry@2a5f9ab
[junit4] 2> 1431439 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
] o.a.s.c.SolrCore [halfdeletedcollection_shard1_replica_n41] CLOSING SolrCore
org.apache.solr.core.SolrCore@3a3f2964
[junit4] 2> 1431439 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.halfdeletedcollection.shard1.replica_n41, tag=977217892
[junit4] 2> 1431439 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.halfdeletedcollection.shard1.leader, tag=977217892
[junit4] 2> 1431455 INFO
(zkCallback-494-thread-2-processing-n:127.0.0.1:40692_solr)
[n:127.0.0.1:40692_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> 1431455 INFO
(zkCallback-494-thread-1-processing-n:127.0.0.1:40692_solr)
[n:127.0.0.1:40692_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> 1431486 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={deleteInstanceDir=true&core=halfdeletedcollection_shard1_replica_n41&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=112
[junit4] 2> 1432356 INFO (qtp1778879221-4386) [n:127.0.0.1:51479_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=halfdeletedcollection&action=DELETE&wt=javabin&version=2} status=0
QTime=1011
[junit4] 2> 1432357 INFO (qtp1778879221-4389) [n:127.0.0.1:51479_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1432357 INFO (qtp1778879221-4389) [n:127.0.0.1:51479_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> 1432359 INFO (qtp1778879221-4389) [n:127.0.0.1:51479_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf&name=halfdeletedcollection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1432360 WARN
(OverseerCollectionConfigSetProcessor-99076617541582858-127.0.0.1:42635_solr-n_0000000000)
[n:127.0.0.1:42635_solr ] o.a.s.c.LockTree lock_is_leaked
at[halfdeletedcollection]
[junit4] 2> 1432360 INFO
(OverseerThreadFactory-1107-thread-4-processing-n:127.0.0.1:42635_solr)
[n:127.0.0.1:42635_solr ] o.a.s.c.LockTree Unlocked multiple times :
halfdeletedcollection
[junit4] 2> 1432403 INFO
(OverseerCollectionConfigSetProcessor-99076617541582858-127.0.0.1:42635_solr-n_0000000000)
[n:127.0.0.1:42635_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> 1432404 INFO
(OverseerThreadFactory-1107-thread-5-processing-n:127.0.0.1:42635_solr)
[n:127.0.0.1:42635_solr ] o.a.s.c.CreateCollectionCmd Create collection
halfdeletedcollection
[junit4] 2> 1432957 INFO
(OverseerStateUpdate-99076617541582858-127.0.0.1:42635_solr-n_0000000000)
[n:127.0.0.1:42635_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"halfdeletedcollection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"halfdeletedcollection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:40692/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1432984 INFO
(OverseerStateUpdate-99076617541582858-127.0.0.1:42635_solr-n_0000000000)
[n:127.0.0.1:42635_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"halfdeletedcollection",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"halfdeletedcollection_shard2_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:57790/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1433205 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=halfdeletedcollection_shard1_replica_n1&action=CREATE&numShards=2&collection=halfdeletedcollection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1433525 INFO
(zkCallback-494-thread-1-processing-n:127.0.0.1:40692_solr)
[n:127.0.0.1:40692_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> 1433525 INFO
(zkCallback-494-thread-2-processing-n:127.0.0.1:40692_solr)
[n:127.0.0.1:40692_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> 1433609 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=halfdeletedcollection_shard2_replica_n3&action=CREATE&numShards=2&collection=halfdeletedcollection&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1433609 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 1433764 INFO
(zkCallback-505-thread-2-processing-n:127.0.0.1:57790_solr)
[n:127.0.0.1:57790_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> 1433764 INFO
(zkCallback-505-thread-1-processing-n:127.0.0.1:57790_solr)
[n:127.0.0.1:57790_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> 1433764 INFO
(zkCallback-494-thread-1-processing-n:127.0.0.1:40692_solr)
[n:127.0.0.1:40692_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> 1433764 INFO
(zkCallback-494-thread-2-processing-n:127.0.0.1:40692_solr)
[n:127.0.0.1:40692_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> 1434245 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.2.0
[junit4] 2> 1434271 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.s.IndexSchema
[halfdeletedcollection_shard1_replica_n1] Schema name=minimal
[junit4] 2> 1434274 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 1434274 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.CoreContainer Creating
SolrCore 'halfdeletedcollection_shard1_replica_n1' using configuration from
collection halfdeletedcollection, trusted=true
[junit4] 2> 1434274 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr.core.halfdeletedcollection.shard1.replica_n1' (registry
'solr.core.halfdeletedcollection.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ddcdf76
[junit4] 2> 1434274 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1434286 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.SolrCore
[[halfdeletedcollection_shard1_replica_n1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_C43CE26D93BF099C-001/tempDir-001/node2/halfdeletedcollection_shard1_replica_n1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_C43CE26D93BF099C-001/tempDir-001/node2/halfdeletedcollection_shard1_replica_n1/data/]
[junit4] 2> 1436045 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.2.0
[junit4] 2> 1436058 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1436058 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1436229 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 1436230 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 1436239 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@71cb23c7[halfdeletedcollection_shard1_replica_n1] main]
[junit4] 2> 1436248 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1436249 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1436249 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000ms.
[junit4] 2> 1436249 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max
version in index or recent updates, using new clock 1585225897358655488
[junit4] 2> 1436265 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.s.IndexSchema
[halfdeletedcollection_shard2_replica_n3] Schema name=minimal
[junit4] 2> 1436432 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 1436432 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.c.CoreContainer Creating
SolrCore 'halfdeletedcollection_shard2_replica_n3' using configuration from
collection halfdeletedcollection, trusted=true
[junit4] 2> 1436432 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr.core.halfdeletedcollection.shard2.replica_n3' (registry
'solr.core.halfdeletedcollection.shard2.replica_n3') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ddcdf76
[junit4] 2> 1436432 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1436432 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.c.SolrCore
[[halfdeletedcollection_shard2_replica_n3] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_C43CE26D93BF099C-001/tempDir-001/node4/halfdeletedcollection_shard2_replica_n3],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_C43CE26D93BF099C-001/tempDir-001/node4/halfdeletedcollection_shard2_replica_n3/data/]
[junit4] 2> 1436447 INFO
(searcherExecutor-1121-thread-1-processing-n:127.0.0.1:40692_solr
x:halfdeletedcollection_shard1_replica_n1 s:shard1 c:halfdeletedcollection
r:core_node2) [n:127.0.0.1:40692_solr c:halfdeletedcollection s:shard1
r:core_node2 x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.SolrCore
[halfdeletedcollection_shard1_replica_n1] Registered new searcher
Searcher@71cb23c7[halfdeletedcollection_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1436501 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext
Enough replicas found to continue.
[junit4] 2> 1436501 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I
may be the new leader - try and sync
[junit4] 2> 1436501 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas
to https://127.0.0.1:40692/solr/halfdeletedcollection_shard1_replica_n1/
[junit4] 2> 1436501 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success -
now sync replicas to me
[junit4] 2> 1436501 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.SyncStrategy
https://127.0.0.1:40692/solr/halfdeletedcollection_shard1_replica_n1/ has no
replicas
[junit4] 2> 1436501 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext
Found all replicas participating in election, clear LIR
[junit4] 2> 1436504 INFO
(zkCallback-505-thread-2-processing-n:127.0.0.1:57790_solr)
[n:127.0.0.1:57790_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> 1436504 INFO
(zkCallback-505-thread-1-processing-n:127.0.0.1:57790_solr)
[n:127.0.0.1:57790_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> 1436504 INFO
(zkCallback-494-thread-2-processing-n:127.0.0.1:40692_solr)
[n:127.0.0.1:40692_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> 1436504 INFO
(zkCallback-494-thread-1-processing-n:127.0.0.1:40692_solr)
[n:127.0.0.1:40692_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> 1436504 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I
am the new leader:
https://127.0.0.1:40692/solr/halfdeletedcollection_shard1_replica_n1/ shard1
[junit4] 2> 1436523 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.c.ZkController I am the
leader, no recovery necessary
[junit4] 2> 1436525 INFO (qtp1447240445-4381) [n:127.0.0.1:40692_solr
c:halfdeletedcollection s:shard1 r:core_node2
x:halfdeletedcollection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=halfdeletedcollection_shard1_replica_n1&action=CREATE&numShards=2&collection=halfdeletedcollection&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=3320
[junit4] 2> 1436625 INFO
(zkCallback-494-thread-2-processing-n:127.0.0.1:40692_solr)
[n:127.0.0.1:40692_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> 1436625 INFO
(zkCallback-494-thread-1-processing-n:127.0.0.1:40692_solr)
[n:127.0.0.1:40692_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> 1436638 INFO
(zkCallback-505-thread-2-processing-n:127.0.0.1:57790_solr)
[n:127.0.0.1:57790_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> 1436638 INFO
(zkCallback-505-thread-1-processing-n:127.0.0.1:57790_solr)
[n:127.0.0.1:57790_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> 1436722 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1436723 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1436724 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 1436724 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 1436824 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.s.SolrIndexSearcher Opening
[Searcher@1ffa8538[halfdeletedcollection_shard2_replica_n3] main]
[junit4] 2> 1436974 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1436998 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1437000 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000ms.
[junit4] 2> 1437000 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.u.UpdateLog Could not find max
version in index or recent updates, using new clock 1585225898146136064
[junit4] 2> 1437064 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext
Enough replicas found to continue.
[junit4] 2> 1437065 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext I
may be the new leader - try and sync
[junit4] 2> 1437065 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.c.SyncStrategy Sync replicas
to https://127.0.0.1:57790/solr/halfdeletedcollection_shard2_replica_n3/
[junit4] 2> 1437065 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.c.SyncStrategy Sync Success -
now sync replicas to me
[junit4] 2> 1437065 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.c.SyncStrategy
https://127.0.0.1:57790/solr/halfdeletedcollection_shard2_replica_n3/ has no
replicas
[junit4] 2> 1437065 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext
Found all replicas participating in election, clear LIR
[junit4] 2> 1437078 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext I
am the new leader:
https://127.0.0.1:57790/solr/halfdeletedcollection_shard2_replica_n3/ shard2
[junit4] 2> 1437083 INFO
(searcherExecutor-1126-thread-1-processing-n:127.0.0.1:57790_solr
x:halfdeletedcollection_shard2_replica_n3 s:shard2 c:halfdeletedcollection
r:core_node4) [n:127.0.0.1:57790_solr c:halfdeletedcollection s:shard2
r:core_node4 x:halfdeletedcollection_shard2_replica_n3] o.a.s.c.SolrCore
[halfdeletedcollection_shard2_replica_n3] Registered new searcher
Searcher@1ffa8538[halfdeletedcollection_shard2_replica_n3]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1437215 INFO
(zkCallback-494-thread-2-processing-n:127.0.0.1:40692_solr)
[n:127.0.0.1:40692_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> 1437215 INFO
(zkCallback-494-thread-1-processing-n:127.0.0.1:40692_solr)
[n:127.0.0.1:40692_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> 1437216 INFO
(zkCallback-505-thread-1-processing-n:127.0.0.1:57790_solr)
[n:127.0.0.1:57790_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> 1437216 INFO
(zkCallback-505-thread-2-processing-n:127.0.0.1:57790_solr)
[n:127.0.0.1:57790_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> 1437258 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.c.ZkController I am the
leader, no recovery necessary
[junit4] 2> 1437260 INFO (qtp1324401651-4402) [n:127.0.0.1:57790_solr
c:halfdeletedcollection s:shard2 r:core_node4
x:halfdeletedcollection_shard2_replica_n3] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=halfdeletedcollection_shard2_replica_n3&action=CREATE&numShards=2&collection=halfdeletedcollection&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=3651
[junit4] 2> 1437414 INFO (qtp1778879221-4389) [n:127.0.0.1:51479_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> 1437427 INFO
(zkCallback-494-thread-2-processing-n:127.0.0.1:40692_solr)
[n:127.0.0.1:40692_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> 1437427 INFO
(zkCallback-494-thread-1-processing-n:127.0.0.1:40692_solr)
[n:127.0.0.1:40692_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> 1437427 INFO
(zkCallback-505-thread-2-processing-n:127.0.0.1:57790_solr)
[n:127.0.0.1:57790_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> 1437462 INFO
(zkCallback-505-thread-1-processing-n:127.0.0.1:57790_solr)
[n:127.0.0.1:57790_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> 1437527 INFO
(OverseerCollectionConfigSetProcessor-99076617541582858-127.0.0.1:42635_solr-n_0000000000)
[n:127.0.0.1:42635_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> 1438419 INFO (qtp1778879221-4389) [n:127.0.0.1:51479_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf&name=halfdeletedcollection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2}
status=0 QTime=6059
[junit4] 2> 1438422 INFO (qtp1778879221-4391) [n:127.0.0.1:51479_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1438422 INFO (qtp1778879221-4391) [n:127.0.0.1:51479_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> 1438423 INFO
(TEST-CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection-seed#[C43CE26D93BF099C])
[ ] o.a.s.SolrTestCaseJ4 ###Ending deletePartiallyCreatedCollection
[junit4] 2> 1438544 INFO
(TEST-CollectionsAPIDistributedZkTest.testZeroNumShards-seed#[C43CE26D93BF099C])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testZeroNumShards
[junit4] 2> 1438545 INFO
(TEST-CollectionsAPIDistributedZkTest.testZeroNumShards-seed#[C43CE26D93BF099C])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1438545 INFO (qtp1778879221-4392) [n:127.0.0.1:51479_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> 1438555 INFO
(OverseerThreadFactory-1107-thread-5-processing-n:127.0.0.1:42635_solr)
[n:127.0.0.1:42635_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 1438568 INFO (qtp1447240445-4375) [n:127.0.0.1:40692_solr
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.halfdeletedcollection.shard1.replica_n1, tag=null
[junit4] 2> 1438568 INFO (qtp1447240445-4375) [n:127.0.0.1:40692_solr
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2d70f2fb: rootName = null,
domain = solr.core.halfdeletedcollection.shard1.replica_n1, service url = null,
agent id = null] for registry solr.core.halfdeletedcollection.shard1.replica_n1
/ com.codahale.metrics.MetricRegistry@54d6885
[junit4] 2> 1438642 INFO (qtp1447240445-4375) [n:127.0.0.1:40692_solr
] o.a.s.c.SolrCore [halfdeletedcollection_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@18776eec
[junit4] 2> 1438642 INFO (qtp1447240445-4375) [n:127.0.0.1:40692_solr
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.halfdeletedcollection.shard1.replica_n1, tag=410480364
[junit4] 2> 1438642 INFO (qtp1447240445-4375) [n:127.0.0.1:40692_solr
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.halfdeletedcollection.shard1.leader, tag=410480364
[junit4] 2> 1438643 INFO (qtp1324401651-4398) [n:127.0.0.1:57790_solr
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.halfdeletedcollection.shard2.replica_n3, tag=null
[junit4] 2> 1438643 INFO (qtp1324401651-4398) [n:127.0.0.1:57790_solr
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@7c40f5b9: rootName = null,
domain = solr.core.halfdeletedcollection.shard2.replica_n3, service url = null,
agent id = null] for registry solr.core.halfdeletedcollection.shard2.replica_n3
/ com.codahale.metrics.MetricRegistry@76f52428
[junit4] 2> 1438673 INFO (qtp1324401651-4398) [n:127.0.0.1:57790_solr
] o.a.s.c.SolrCore [halfdeletedcollection_shard2_replica_n3] CLOSING SolrCore
org.apache.solr.core.SolrCore@19348749
[junit4] 2> 1438673 INFO (qtp1324401651-4398) [n:127.0.0.1:57790_solr
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.halfdeletedcollection.shard2.replica_n3, tag=422872905
[junit4] 2> 1438673 INFO (qtp1324401651-4398) [n:127.0.0.1:57790_solr
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.halfdeletedcollection.shard2.leader, tag=422872905
[junit4] 2> 1438681 INFO (qtp1447240445-4375) [n:127.0.0.1:40692_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={deleteInstanceDir=true&core=halfdeletedcollection_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=113
[junit4] 2> 1438687 INFO (qtp1324401651-4398) [n:127.0.0.1:57790_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={deleteInstanceDir=true&core=halfdeletedcollection_shard2_replica_n3&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=43
[junit4] 2> 1439624 INFO (qtp1778879221-4392) [n:127.0.0.1:51479_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=halfdeletedcollection&action=DELETE&wt=javabin&version=2} status=0
QTime=1079
[junit4] 2> 1439628 INFO (qtp1778879221-4393) [n:127.0.0.1:51479_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=10&collection.configName=conf&name=acollection&action=CREATE&numShards=0&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1439632 INFO
(OverseerCollectionConfigSetProcessor-99076617541582858-127.0.0.1:42635_solr-n_0000000000)
[n:127.0.0.1:42635_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> 1439635 INFO
(OverseerThreadFactory-1107-thread-5-processing-n:127.0.0.1:42635_solr)
[n:127.0.0.1:42635_solr ] o.a.s.c.CreateCollectionCmd Create collection
acollection
[junit4] 2> 1439638 ERROR
(OverseerThreadFactory-1107-thread-5-processing-n:127.0.0.1:42635_solr)
[n:127.0.0.1:42635_solr ] o.a.s.c.OverseerCollectionMessageHandler
Collection: acollection operation: create
failed:org.apache.solr.common.SolrException: numShards must be > 0
[junit4] 2> at
org.apache.solr.cloud.CreateCollectionCmd.call(CreateCollectionCmd.java:144)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:232)
[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:188)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1439672 INFO (qtp1778879221-4393) [n:127.0.0.1:51479_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=10&collection.configName=conf&name=acollection&action=CREATE&numShards=0&wt=javabin&version=2}
status=400 QTime=43
[junit4] 2> 1439672 INFO
(TEST-CollectionsAPIDistributedZkTest.testZeroNumShards-seed#[C43CE26D93BF099C])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testZeroNumShards
[junit4] 2> 1439808 INFO
(TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[C43CE26D93BF099C])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testTooManyReplicas
[junit4] 2> 1439809 INFO
(TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[C43CE26D93BF099C])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1439810 INFO (qtp1778879221-4386) [n:127.0.0.1:51479_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=10&collection.configName=conf&name=collection&nrtReplicas=10&action=CREATE&numShards=2&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1439827 INFO
(OverseerThreadFactory-1107-thread-5-processing-n:127.0.0.1:42635_solr)
[n:127.0.0.1:42635_solr ] o.a.s.c.CreateCollectionCmd Create collection
collection
[junit4] 2> 1439827 WARN
(OverseerThreadFactory-1107-thread-5-processing-n:127.0.0.1:42635_solr)
[n:127.0.0.1:42635_solr ] o.a.s.c.CreateCollectionCmd Specified number of
replicas of 10 on collection collection is higher than 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> 1439827 ERROR
(OverseerThreadFactory-1107-thread-5-processing-n:127.0.0.1:42635_solr)
[n:127.0.0.1:42635_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, value of nrtReplicas is 10, value of
tlogReplicas is 0 and value of pullReplicas is 0. This requires 20 shards to be
created (higher than the allowed number)
[junit4] 2> at
org.apache.solr.cloud.CreateCollectionCmd.call(CreateCollectionCmd.java:176)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:232)
[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:188)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1439828 INFO
(OverseerCollectionConfigSetProcessor-99076617541582858-127.0.0.1:42635_solr-n_0000000000)
[n:127.0.0.1:42635_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> 1439829 INFO (qtp1778879221-4386) [n:127.0.0.1:51479_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=10&collection.configName=conf&name=collection&nrtReplicas=10&action=CREATE&numShards=2&wt=javabin&version=2}
status=400 QTime=19
[junit4] 2> 1439830 INFO
(OverseerCollectionConfigSetProcessor-99076617541582858-127.0.0.1:42635_solr-n_0000000000)
[n:127.0.0.1:42635_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000014 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1439831 INFO
(TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[C43CE26D93BF099C])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testTooManyReplicas
[junit4] 2> 1441140 INFO
(TEST-CollectionsAPIDistributedZkTest.testBadActionNames-seed#[C43CE26D93BF099C])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testBadActionNames
[junit4] 2> 1441141 INFO
(TEST-CollectionsAPIDistributedZkTest.testBadActionNames-seed#[C43CE26D93BF099C])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1441163 ERROR (qtp1778879221-4390) [n:127.0.0.1:51479_solr
] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: Unknown
action: BADACTION
[junit4] 2> at
org.apache.solr.handler.admin.CollectionsHandler.handleRequestBody(CollectionsHandler.java:217)
[junit4] 2> at
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:735)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:716)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:497)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:493)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at
org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:251)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1441163 INFO (qtp1778879221-4390) [n:127.0.0.1:51479_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=badactioncollection&action=BADACTION&numShards=2&wt=javabin&version=2}
status=400 QTime=0
[junit4] 2> 1441163 INFO
(TEST-CollectionsAPIDistributedZkTest.testBadActionNames-seed#[C43CE26D93BF099C])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testBadActionNames
[junit4] 2> 1441323 INFO
(TEST-CollectionsAPIDistributedZkTest.testCollectionsAPI-seed#[C43CE26D93BF099C])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testCollectionsAPI
[junit4] 2> 1441324 INFO
(TEST-CollectionsAPIDistributedZkTest.testCollectionsAPI-seed#[C43CE26D93BF099C])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1441326 INFO (qtp1778879221-4389) [n:127.0.0.1:51479_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
async=650b07bb-6f2a-4e67-89de-4807417dc124&replicationFactor=2&collection.configName=conf2&maxShardsPerNode=3&name=awhollynewcollection_0&nrtReplicas=2&action=CREATE&numShards=5&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1441328 INFO (qtp1778879221-4389) [n:127.0.0.1:51479_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={async=650b07bb-6f2a-4e67-89de-4807417dc124&replicationFactor=2&collection.configName=conf2&maxShardsPerNode=3&name=awhollynewcollection_0&nrtReplicas=2&action=CREATE&numShards=5&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 1441329 INFO
(OverseerThreadFactory-1107-thread-5-processing-n:127.0.0.1:42635_solr)
[n:127.0.0.1:42635_solr ] o.a.s.c.CreateCollectionCmd Create collection
awhollynewcollection_0
[junit4] 2> 1441488 INFO
(OverseerStateUpdate-99076617541582858-127.0.0.1:42635_solr-n_0000000000)
[n:127.0.0.1:42635_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"awhollynewcollection_0",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"awhollynewcollection_0_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:42635/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1441489 INFO
(OverseerStateUpdate-99076617541582858-127.0.0.1:42635_solr-n_0000000000)
[n:127.0.0.1:42635_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"awhollynewcollection_0",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"awhollynewcollection_0_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:51479/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1441489 INFO
(OverseerStateUpdate-99076617541582858-127.0.0.1:42635_solr-n_0000000000)
[n:127.0.0.1:42635_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"awhollynewcollection_0",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"awhollynewcollection_0_shar
[...truncated too long message...]
slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptor-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-admin-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-authn-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-authz-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-changelog-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/byte-buddy-1.6.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/commons-collections-3.2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/commons-math3-3.6.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/hadoop-common-2.7.4-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/hadoop-hdfs-2.7.4-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/hadoop-minikdc-2.7.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/htrace-core-3.2.0-incubating.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/jetty-sslengine-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/mockito-core-2.6.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/netty-all-4.0.36.Final.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/objenesis-2.5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/build/analysis/icu/lucene-analyzers-icu-7.2.0-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/build/queryparser/classes/test:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/build/backward-codecs/classes/test:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/contrib/analysis-extras/lib/icu4j-59.1.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-launcher.jar:/x1/jenkins/.ant/lib/ivy-2.4.0.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-junit.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-log4j.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-junit4.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jai.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-javamail.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-bsf.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-commons-net.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-antlr.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jsch.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-oro.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-commons-logging.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-netrexx.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-testutil.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jdepend.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-bcel.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-xalan2.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-resolver.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jmf.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-regexp.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-swing.jar:/usr/local/asfpackages/java/jdk1.8.0_144/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.5.3.jar
com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/temp/junit4-J2-20171127_130246_6549064630013278040268.events
@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/temp/junit4-J2-20171127_130246_6542543583330629795665.suites
-stdin
[junit4] ERROR: JVM J2 ended with an exception: Forked process returned with
error code: 1. Very likely a JVM crash. See process stdout at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/temp/junit4-J2-20171127_130246_6547388988048926381120.sysout
See process stderr at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/temp/junit4-J2-20171127_130246_6544514217645843544710.syserr
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1519)
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:126)
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:982)
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:979)
[junit4] at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] at java.lang.Thread.run(Thread.java:748)
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/build.xml:835: The
following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/build.xml:779: The
following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/build.xml:59: The
following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build.xml:262:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/common-build.xml:556:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/common-build.xml:1512:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/common-build.xml:1038:
At least one slave process threw an exception, first: Forked process returned
with error code: 1. Very likely a JVM crash. See process stdout at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/temp/junit4-J0-20171127_130246_6522659386859843272668.sysout
See process stderr at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/temp/junit4-J0-20171127_130246_6523585915014860266223.syserr
Total time: 368 minutes 1 second
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[Fast Archiver] No artifacts from Lucene-Solr-Tests-7.x #258 to compare, so
performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
