Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.0/59/
2 tests failed.
FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.addReplicaTest
Error Message:
Could not load collection from ZK: addReplicaColl
Stack Trace:
org.apache.solr.common.SolrException: Could not load collection from ZK:
addReplicaColl
at
__randomizedtesting.SeedInfo.seed([EAE2F1DB869B2C7A:7942A6FD089367AB]:0)
at
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1112)
at
org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:646)
at
org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:213)
at
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:193)
at
org.apache.solr.cloud.SolrCloudTestCase.getCollectionState(SolrCloudTestCase.java:247)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.addReplicaTest(CollectionsAPIDistributedZkTest.java:655)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for /collections/addReplicaColl/state.json
at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1212)
at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:357)
at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:354)
at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
at
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:354)
at
org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1124)
at
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1110)
... 44 more
FAILED: org.apache.solr.client.solrj.TestSolrJErrorHandling.testWithXml
Error Message:
expected:<914> but was:<904>
Stack Trace:
java.lang.AssertionError: expected:<914> but was:<904>
at
__randomizedtesting.SeedInfo.seed([67D6F6A4B353A378:CC344F523155BA15]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at
org.apache.solr.client.solrj.TestSolrJErrorHandling.doThreads(TestSolrJErrorHandling.java:185)
at
org.apache.solr.client.solrj.TestSolrJErrorHandling.doIt(TestSolrJErrorHandling.java:200)
at
org.apache.solr.client.solrj.TestSolrJErrorHandling.testWithXml(TestSolrJErrorHandling.java:110)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 11217 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.0/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EAE2F1DB869B2C7A-001/init-core-data-001
[junit4] 2> 643718 WARN
(SUITE-CollectionsAPIDistributedZkTest-seed#[EAE2F1DB869B2C7A]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=20 numCloses=20
[junit4] 2> 643755 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[EAE2F1DB869B2C7A]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 643756 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[EAE2F1DB869B2C7A]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 643756 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[EAE2F1DB869B2C7A]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.0/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EAE2F1DB869B2C7A-001/tempDir-001
[junit4] 2> 643756 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[EAE2F1DB869B2C7A]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 643775 INFO (Thread-1115) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 643775 INFO (Thread-1115) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 643860 ERROR (Thread-1115) [ ] 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> 643884 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[EAE2F1DB869B2C7A]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:38695
[junit4] 2> 643920 INFO (jetty-launcher-389-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 643922 INFO (jetty-launcher-389-thread-2) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 643934 INFO (jetty-launcher-389-thread-3) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 643934 INFO (jetty-launcher-389-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@497e656c{/solr,null,AVAILABLE}
[junit4] 2> 643945 INFO (jetty-launcher-389-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@5c30cf9c{HTTP/1.1,[http/1.1]}{127.0.0.1:37077}
[junit4] 2> 643945 INFO (jetty-launcher-389-thread-1) [ ]
o.e.j.s.Server Started @654642ms
[junit4] 2> 643945 INFO (jetty-launcher-389-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=37077}
[junit4] 2> 643966 ERROR (jetty-launcher-389-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 643966 INFO (jetty-launcher-389-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 643966 INFO (jetty-launcher-389-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 643966 INFO (jetty-launcher-389-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 643966 INFO (jetty-launcher-389-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-21T23:16:20.272Z
[junit4] 2> 643996 INFO (jetty-launcher-389-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@454cde3d{/solr,null,AVAILABLE}
[junit4] 2> 643996 INFO (jetty-launcher-389-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@6e1181f9{HTTP/1.1,[http/1.1]}{127.0.0.1:38350}
[junit4] 2> 643996 INFO (jetty-launcher-389-thread-2) [ ]
o.e.j.s.Server Started @654693ms
[junit4] 2> 643996 INFO (jetty-launcher-389-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=38350}
[junit4] 2> 643996 ERROR (jetty-launcher-389-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 643996 INFO (jetty-launcher-389-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 643996 INFO (jetty-launcher-389-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 643996 INFO (jetty-launcher-389-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 643996 INFO (jetty-launcher-389-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-21T23:16:20.302Z
[junit4] 2> 644000 INFO (jetty-launcher-389-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6062d922{/solr,null,AVAILABLE}
[junit4] 2> 644000 INFO (jetty-launcher-389-thread-3) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@77de4d2{HTTP/1.1,[http/1.1]}{127.0.0.1:35625}
[junit4] 2> 644000 INFO (jetty-launcher-389-thread-3) [ ]
o.e.j.s.Server Started @654697ms
[junit4] 2> 644000 INFO (jetty-launcher-389-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=35625}
[junit4] 2> 644000 ERROR (jetty-launcher-389-thread-3) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 644000 INFO (jetty-launcher-389-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 644000 INFO (jetty-launcher-389-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 644000 INFO (jetty-launcher-389-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 644000 INFO (jetty-launcher-389-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-21T23:16:20.306Z
[junit4] 2> 644088 INFO (jetty-launcher-389-thread-4) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 644117 INFO (jetty-launcher-389-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@32ebeb8c{/solr,null,AVAILABLE}
[junit4] 2> 644117 INFO (jetty-launcher-389-thread-4) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@5f7fa6a7{HTTP/1.1,[http/1.1]}{127.0.0.1:35909}
[junit4] 2> 644117 INFO (jetty-launcher-389-thread-4) [ ]
o.e.j.s.Server Started @654814ms
[junit4] 2> 644117 INFO (jetty-launcher-389-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=35909}
[junit4] 2> 644117 ERROR (jetty-launcher-389-thread-4) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 644117 INFO (jetty-launcher-389-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 644118 INFO (jetty-launcher-389-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 644118 INFO (jetty-launcher-389-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 644118 INFO (jetty-launcher-389-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-21T23:16:20.424Z
[junit4] 2> 644119 INFO (jetty-launcher-389-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 644130 INFO (jetty-launcher-389-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 644161 INFO (jetty-launcher-389-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 644161 INFO (jetty-launcher-389-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 644210 INFO (jetty-launcher-389-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38695/solr
[junit4] 2> 644346 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 0x15d677002110004, 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> 644381 INFO (jetty-launcher-389-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38695/solr
[junit4] 2> 644382 INFO (jetty-launcher-389-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38695/solr
[junit4] 2> 644384 INFO (jetty-launcher-389-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38695/solr
[junit4] 2> 644475 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 0x15d677002110006, 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> 644503 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 0x15d677002110005, 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> 644635 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 0x15d677002110008, 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> 644703 INFO (jetty-launcher-389-thread-2)
[n:127.0.0.1:38350_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 644703 INFO (jetty-launcher-389-thread-4)
[n:127.0.0.1:35909_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 644704 INFO (jetty-launcher-389-thread-2)
[n:127.0.0.1:38350_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:38350_solr
[junit4] 2> 644704 INFO (jetty-launcher-389-thread-4)
[n:127.0.0.1:35909_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:35909_solr
[junit4] 2> 644705 INFO (jetty-launcher-389-thread-2)
[n:127.0.0.1:38350_solr ] o.a.s.c.Overseer Overseer
(id=98348497640685580-127.0.0.1:38350_solr-n_0000000000) starting
[junit4] 2> 644762 INFO (jetty-launcher-389-thread-1)
[n:127.0.0.1:37077_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 644763 INFO (jetty-launcher-389-thread-2)
[n:127.0.0.1:38350_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:38350_solr
[junit4] 2> 644780 INFO
(zkCallback-410-thread-1-processing-n:127.0.0.1:37077_solr)
[n:127.0.0.1:37077_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 644780 INFO
(OverseerStateUpdate-98348497640685580-127.0.0.1:38350_solr-n_0000000000)
[n:127.0.0.1:38350_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 644795 INFO
(zkCallback-411-thread-1-processing-n:127.0.0.1:35909_solr)
[n:127.0.0.1:35909_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 644798 INFO
(zkCallback-412-thread-1-processing-n:127.0.0.1:38350_solr)
[n:127.0.0.1:38350_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 644831 INFO
(zkCallback-410-thread-1-processing-n:127.0.0.1:37077_solr)
[n:127.0.0.1:37077_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 644831 INFO
(zkCallback-411-thread-1-processing-n:127.0.0.1:35909_solr)
[n:127.0.0.1:35909_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 644852 INFO (jetty-launcher-389-thread-1)
[n:127.0.0.1:37077_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:37077_solr
[junit4] 2> 644925 INFO
(zkCallback-412-thread-2-processing-n:127.0.0.1:38350_solr)
[n:127.0.0.1:38350_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 644941 INFO
(zkCallback-410-thread-1-processing-n:127.0.0.1:37077_solr)
[n:127.0.0.1:37077_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 644941 INFO
(zkCallback-411-thread-1-processing-n:127.0.0.1:35909_solr)
[n:127.0.0.1:35909_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 645030 INFO (jetty-launcher-389-thread-4)
[n:127.0.0.1:35909_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@a2c2f74
[junit4] 2> 645049 INFO (jetty-launcher-389-thread-4)
[n:127.0.0.1:35909_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@a2c2f74
[junit4] 2> 645049 INFO (jetty-launcher-389-thread-4)
[n:127.0.0.1:35909_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@a2c2f74
[junit4] 2> 645050 INFO (jetty-launcher-389-thread-4)
[n:127.0.0.1:35909_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.0/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EAE2F1DB869B2C7A-001/tempDir-001/node4
[junit4] 2> 645086 INFO (jetty-launcher-389-thread-2)
[n:127.0.0.1:38350_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@a2c2f74
[junit4] 2> 645139 INFO (jetty-launcher-389-thread-2)
[n:127.0.0.1:38350_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@a2c2f74
[junit4] 2> 645139 INFO (jetty-launcher-389-thread-2)
[n:127.0.0.1:38350_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@a2c2f74
[junit4] 2> 645157 INFO (jetty-launcher-389-thread-3)
[n:127.0.0.1:35625_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 645158 INFO (jetty-launcher-389-thread-3)
[n:127.0.0.1:35625_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 645159 INFO (jetty-launcher-389-thread-3)
[n:127.0.0.1:35625_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:35625_solr
[junit4] 2> 645172 INFO
(zkCallback-412-thread-2-processing-n:127.0.0.1:38350_solr)
[n:127.0.0.1:38350_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 645173 INFO (jetty-launcher-389-thread-2)
[n:127.0.0.1:38350_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.0/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EAE2F1DB869B2C7A-001/tempDir-001/node2
[junit4] 2> 645180 INFO
(zkCallback-411-thread-1-processing-n:127.0.0.1:35909_solr)
[n:127.0.0.1:35909_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 645180 INFO
(zkCallback-410-thread-1-processing-n:127.0.0.1:37077_solr)
[n:127.0.0.1:37077_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 645237 INFO
(zkCallback-408-thread-1-processing-n:127.0.0.1:35625_solr)
[n:127.0.0.1:35625_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 645419 INFO (jetty-launcher-389-thread-3)
[n:127.0.0.1:35625_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@a2c2f74
[junit4] 2> 645454 INFO (jetty-launcher-389-thread-3)
[n:127.0.0.1:35625_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@a2c2f74
[junit4] 2> 645454 INFO (jetty-launcher-389-thread-3)
[n:127.0.0.1:35625_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@a2c2f74
[junit4] 2> 645455 INFO (jetty-launcher-389-thread-3)
[n:127.0.0.1:35625_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.0/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EAE2F1DB869B2C7A-001/tempDir-001/node3
[junit4] 2> 645552 INFO (jetty-launcher-389-thread-1)
[n:127.0.0.1:37077_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@a2c2f74
[junit4] 2> 645590 INFO (jetty-launcher-389-thread-1)
[n:127.0.0.1:37077_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@a2c2f74
[junit4] 2> 645590 INFO (jetty-launcher-389-thread-1)
[n:127.0.0.1:37077_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@a2c2f74
[junit4] 2> 645603 INFO (jetty-launcher-389-thread-1)
[n:127.0.0.1:37077_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.0/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EAE2F1DB869B2C7A-001/tempDir-001/node1
[junit4] 2> 645799 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[EAE2F1DB869B2C7A]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 645800 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[EAE2F1DB869B2C7A]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38695/solr ready
[junit4] 2> 645886 INFO
(TEST-CollectionsAPIDistributedZkTest.testMaxNodesPerShard-seed#[EAE2F1DB869B2C7A])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testMaxNodesPerShard
[junit4] 2> 645899 INFO
(TEST-CollectionsAPIDistributedZkTest.testMaxNodesPerShard-seed#[EAE2F1DB869B2C7A])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 645913 INFO (qtp849232298-3023) [n:127.0.0.1:35909_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=2&collection.configName=conf&name=oversharded&nrtReplicas=2&action=CREATE&numShards=3&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 645932 INFO
(OverseerThreadFactory-1115-thread-1-processing-n:127.0.0.1:38350_solr)
[n:127.0.0.1:38350_solr ] o.a.s.c.CreateCollectionCmd Create collection
oversharded
[junit4] 2> 645933 ERROR
(OverseerThreadFactory-1115-thread-1-processing-n:127.0.0.1:38350_solr)
[n:127.0.0.1:38350_solr ] o.a.s.c.OverseerCollectionMessageHandler
Collection: oversharded operation: create
failed:org.apache.solr.common.SolrException: Cannot create collection
oversharded. Value of maxShardsPerNode is 1, and the number of nodes currently
live or live and part of your createNodeSet is 4. This allows a maximum of 4 to
be created. Value of numShards is 3, value of nrtReplicas is 2, value of
tlogReplicas is 0 and value of pullReplicas is 0. This requires 6 shards to be
created (higher than the allowed number)
[junit4] 2> at
org.apache.solr.cloud.CreateCollectionCmd.call(CreateCollectionCmd.java:157)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:227)
[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:1142)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 645949 INFO (qtp849232298-3023) [n:127.0.0.1:35909_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=2&collection.configName=conf&name=oversharded&nrtReplicas=2&action=CREATE&numShards=3&wt=javabin&version=2}
status=400 QTime=35
[junit4] 2> 645968 INFO
(TEST-CollectionsAPIDistributedZkTest.testMaxNodesPerShard-seed#[EAE2F1DB869B2C7A])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testMaxNodesPerShard
[junit4] 2> 646072 INFO
(TEST-CollectionsAPIDistributedZkTest.testCollectionsAPI-seed#[EAE2F1DB869B2C7A])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testCollectionsAPI
[junit4] 2> 646082 INFO
(TEST-CollectionsAPIDistributedZkTest.testCollectionsAPI-seed#[EAE2F1DB869B2C7A])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 646084 INFO (qtp849232298-3024) [n:127.0.0.1:35909_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
async=e1d9711f-b616-4eb3-984a-812b603f98a9&replicationFactor=1&collection.configName=conf2&maxShardsPerNode=2&name=awhollynewcollection_0&nrtReplicas=1&action=CREATE&numShards=4&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 646085 INFO (qtp849232298-3024) [n:127.0.0.1:35909_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={async=e1d9711f-b616-4eb3-984a-812b603f98a9&replicationFactor=1&collection.configName=conf2&maxShardsPerNode=2&name=awhollynewcollection_0&nrtReplicas=1&action=CREATE&numShards=4&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 646118 INFO
(OverseerCollectionConfigSetProcessor-98348497640685580-127.0.0.1:38350_solr-n_0000000000)
[n:127.0.0.1:38350_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> 646121 INFO
(OverseerThreadFactory-1115-thread-2-processing-n:127.0.0.1:38350_solr)
[n:127.0.0.1:38350_solr ] o.a.s.c.CreateCollectionCmd Create collection
awhollynewcollection_0
[junit4] 2> 646146 INFO (zkCallback-419-thread-3) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeCreated
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 646248 INFO
(OverseerStateUpdate-98348497640685580-127.0.0.1:38350_solr-n_0000000000)
[n:127.0.0.1:38350_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":"http://127.0.0.1:35625/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 646249 INFO
(OverseerStateUpdate-98348497640685580-127.0.0.1:38350_solr-n_0000000000)
[n:127.0.0.1:38350_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_shard2_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:35909/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 646250 INFO
(OverseerStateUpdate-98348497640685580-127.0.0.1:38350_solr-n_0000000000)
[n:127.0.0.1:38350_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"awhollynewcollection_0",
[junit4] 2> "shard":"shard3",
[junit4] 2> "core":"awhollynewcollection_0_shard3_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:37077/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 646251 INFO
(OverseerStateUpdate-98348497640685580-127.0.0.1:38350_solr-n_0000000000)
[n:127.0.0.1:38350_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"awhollynewcollection_0",
[junit4] 2> "shard":"shard4",
[junit4] 2> "core":"awhollynewcollection_0_shard4_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:38350/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 646353 INFO (zkCallback-419-thread-3) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 646535 INFO (qtp329764030-2986) [n:127.0.0.1:37077_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430&coreNodeName=core_node3&name=awhollynewcollection_0_shard3_replica_n1&action=CREATE&numShards=4&shard=shard3&wt=javabin}
status=0 QTime=0
[junit4] 2> 646561 INFO (qtp849232298-3026) [n:127.0.0.1:35909_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104&coreNodeName=core_node2&name=awhollynewcollection_0_shard2_replica_n1&action=CREATE&numShards=4&shard=shard2&wt=javabin}
status=0 QTime=0
[junit4] 2> 646562 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr ] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104&coreNodeName=core_node2&name=awhollynewcollection_0_shard2_replica_n1&action=CREATE&numShards=4&shard=shard2&wt=javabin
[junit4] 2> 646563 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 646563 INFO (qtp1309408691-3009) [n:127.0.0.1:35625_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992&coreNodeName=core_node1&name=awhollynewcollection_0_shard1_replica_n1&action=CREATE&numShards=4&shard=shard1&wt=javabin}
status=0 QTime=0
[junit4] 2> 646563 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr ] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992&coreNodeName=core_node1&name=awhollynewcollection_0_shard1_replica_n1&action=CREATE&numShards=4&shard=shard1&wt=javabin
[junit4] 2> 646564 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 646564 INFO (qtp821941157-2998) [n:127.0.0.1:38350_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870&coreNodeName=core_node4&name=awhollynewcollection_0_shard4_replica_n1&action=CREATE&numShards=4&shard=shard4&wt=javabin}
status=0 QTime=0
[junit4] 2> 646564 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr ] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870&coreNodeName=core_node4&name=awhollynewcollection_0_shard4_replica_n1&action=CREATE&numShards=4&shard=shard4&wt=javabin
[junit4] 2> 646564 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 646578 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr ] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf2&newCollection=true&collection=awhollynewcollection_0&version=2&replicaType=NRT&async=e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430&coreNodeName=core_node3&name=awhollynewcollection_0_shard3_replica_n1&action=CREATE&numShards=4&shard=shard3&wt=javabin
[junit4] 2> 646578 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 646582 INFO (qtp329764030-2986) [n:127.0.0.1:37077_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430
[junit4] 2> 646582 INFO (qtp329764030-2986) [n:127.0.0.1:37077_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 646702 INFO
(zkCallback-411-thread-1-processing-n:127.0.0.1:35909_solr)
[n:127.0.0.1:35909_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 646702 INFO
(zkCallback-410-thread-1-processing-n:127.0.0.1:37077_solr)
[n:127.0.0.1:37077_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 646702 INFO
(zkCallback-408-thread-1-processing-n:127.0.0.1:35625_solr)
[n:127.0.0.1:35625_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 646702 INFO (zkCallback-419-thread-3) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 646702 INFO
(zkCallback-412-thread-2-processing-n:127.0.0.1:38350_solr)
[n:127.0.0.1:38350_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 646702 INFO
(zkCallback-412-thread-1-processing-n:127.0.0.1:38350_solr)
[n:127.0.0.1:38350_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 646702 INFO
(zkCallback-411-thread-2-processing-n:127.0.0.1:35909_solr)
[n:127.0.0.1:35909_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 646702 INFO
(zkCallback-410-thread-2-processing-n:127.0.0.1:37077_solr)
[n:127.0.0.1:37077_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 646702 INFO
(zkCallback-408-thread-2-processing-n:127.0.0.1:35625_solr)
[n:127.0.0.1:35625_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 647593 INFO (qtp329764030-2986) [n:127.0.0.1:37077_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430
[junit4] 2> 647593 INFO (qtp329764030-2986) [n:127.0.0.1:37077_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 647763 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.0.0
[junit4] 2> 647931 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.0.0
[junit4] 2> 648027 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.0.0
[junit4] 2> 648043 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.0.0
[junit4] 2> 648047 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.s.IndexSchema
[awhollynewcollection_0_shard3_replica_n1] Schema name=minimal
[junit4] 2> 648050 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 648050 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.c.CoreContainer Creating
SolrCore 'awhollynewcollection_0_shard3_replica_n1' using configuration from
collection awhollynewcollection_0, trusted=true
[junit4] 2> 648082 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr.core.awhollynewcollection_0.shard3.replica_n1' (registry
'solr.core.awhollynewcollection_0.shard3.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@a2c2f74
[junit4] 2> 648083 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 648083 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.c.SolrCore
[[awhollynewcollection_0_shard3_replica_n1] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.0/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EAE2F1DB869B2C7A-001/tempDir-001/node1/awhollynewcollection_0_shard3_replica_n1],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.0/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EAE2F1DB869B2C7A-001/tempDir-001/node1/awhollynewcollection_0_shard3_replica_n1/data/]
[junit4] 2> 648092 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.s.IndexSchema
[awhollynewcollection_0_shard2_replica_n1] Schema name=minimal
[junit4] 2> 648094 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 648094 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.c.CoreContainer Creating
SolrCore 'awhollynewcollection_0_shard2_replica_n1' using configuration from
collection awhollynewcollection_0, trusted=true
[junit4] 2> 648094 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr.core.awhollynewcollection_0.shard2.replica_n1' (registry
'solr.core.awhollynewcollection_0.shard2.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@a2c2f74
[junit4] 2> 648116 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.s.IndexSchema
[awhollynewcollection_0_shard1_replica_n1] Schema name=minimal
[junit4] 2> 648118 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 648118 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.CoreContainer Creating
SolrCore 'awhollynewcollection_0_shard1_replica_n1' using configuration from
collection awhollynewcollection_0, trusted=true
[junit4] 2> 648119 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr.core.awhollynewcollection_0.shard1.replica_n1' (registry
'solr.core.awhollynewcollection_0.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@a2c2f74
[junit4] 2> 648151 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 648151 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.SolrCore
[[awhollynewcollection_0_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.0/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EAE2F1DB869B2C7A-001/tempDir-001/node3/awhollynewcollection_0_shard1_replica_n1],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.0/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EAE2F1DB869B2C7A-001/tempDir-001/node3/awhollynewcollection_0_shard1_replica_n1/data/]
[junit4] 2> 648127 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 648163 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.c.SolrCore
[[awhollynewcollection_0_shard2_replica_n1] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.0/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EAE2F1DB869B2C7A-001/tempDir-001/node4/awhollynewcollection_0_shard2_replica_n1],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.0/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EAE2F1DB869B2C7A-001/tempDir-001/node4/awhollynewcollection_0_shard2_replica_n1/data/]
[junit4] 2> 648177 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.s.IndexSchema
[awhollynewcollection_0_shard4_replica_n1] Schema name=minimal
[junit4] 2> 648189 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 648189 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.c.CoreContainer Creating
SolrCore 'awhollynewcollection_0_shard4_replica_n1' using configuration from
collection awhollynewcollection_0, trusted=true
[junit4] 2> 648190 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr.core.awhollynewcollection_0.shard4.replica_n1' (registry
'solr.core.awhollynewcollection_0.shard4.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@a2c2f74
[junit4] 2> 648190 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 648190 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.c.SolrCore
[[awhollynewcollection_0_shard4_replica_n1] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.0/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EAE2F1DB869B2C7A-001/tempDir-001/node2/awhollynewcollection_0_shard4_replica_n1],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.0/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EAE2F1DB869B2C7A-001/tempDir-001/node2/awhollynewcollection_0_shard4_replica_n1/data/]
[junit4] 2> 648407 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 648407 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 648408 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 648408 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 648409 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@55fd1ad6[awhollynewcollection_0_shard3_replica_n1] main]
[junit4] 2> 648609 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 648615 INFO (qtp329764030-2986) [n:127.0.0.1:37077_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430
[junit4] 2> 648615 INFO (qtp329764030-2986) [n:127.0.0.1:37077_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 648646 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 648646 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000
[junit4] 2> 648658 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 648658 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 648659 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 648659 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 648660 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.u.UpdateLog Could not find
max version in index or recent updates, using new clock 1573575967339708416
[junit4] 2> 648696 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@41f6f92f[awhollynewcollection_0_shard1_replica_n1] main]
[junit4] 2> 648697 INFO
(searcherExecutor-1124-thread-1-processing-x:awhollynewcollection_0_shard3_replica_n1
r:core_node3 e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE
n:127.0.0.1:37077_solr s:shard3 c:awhollynewcollection_0)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.c.SolrCore
[awhollynewcollection_0_shard3_replica_n1] Registered new searcher
Searcher@55fd1ad6[awhollynewcollection_0_shard3_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 648703 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 648703 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 648704 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 648704 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 648705 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@3b945111[awhollynewcollection_0_shard2_replica_n1] main]
[junit4] 2> 648712 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 648712 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 648713 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 648713 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 648714 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@2b60b756[awhollynewcollection_0_shard4_replica_n1] main]
[junit4] 2> 648733 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 648734 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 648734 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000
[junit4] 2> 648734 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.u.UpdateLog Could not find
max version in index or recent updates, using new clock 1573575967417303040
[junit4] 2> 648791 INFO
(searcherExecutor-1125-thread-1-processing-x:awhollynewcollection_0_shard2_replica_n1
r:core_node2 e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE
n:127.0.0.1:35909_solr s:shard2 c:awhollynewcollection_0)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.c.SolrCore
[awhollynewcollection_0_shard2_replica_n1] Registered new searcher
Searcher@3b945111[awhollynewcollection_0_shard2_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 648807 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.c.ShardLeaderElectionContext
Enough replicas found to continue.
[junit4] 2> 648807 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.c.ShardLeaderElectionContext
I may be the new leader - try and sync
[junit4] 2> 648807 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.c.SyncStrategy Sync replicas
to http://127.0.0.1:37077/solr/awhollynewcollection_0_shard3_replica_n1/
[junit4] 2> 648807 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.c.SyncStrategy Sync Success -
now sync replicas to me
[junit4] 2> 648807 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.c.SyncStrategy
http://127.0.0.1:37077/solr/awhollynewcollection_0_shard3_replica_n1/ has no
replicas
[junit4] 2> 648807 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.c.ShardLeaderElectionContext
Found all replicas participating in election, clear LIR
[junit4] 2> 648808 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext
Enough replicas found to continue.
[junit4] 2> 648808 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext
I may be the new leader - try and sync
[junit4] 2> 648808 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.c.SyncStrategy Sync replicas
to http://127.0.0.1:35909/solr/awhollynewcollection_0_shard2_replica_n1/
[junit4] 2> 648808 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.c.SyncStrategy Sync Success -
now sync replicas to me
[junit4] 2> 648808 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.c.SyncStrategy
http://127.0.0.1:35909/solr/awhollynewcollection_0_shard2_replica_n1/ has no
replicas
[junit4] 2> 648808 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext
Found all replicas participating in election, clear LIR
[junit4] 2> 648812 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 648812 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 648833 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 648834 INFO
(parallelCoreAdminExecutor-1116-thread-1-processing-n:127.0.0.1:35909_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291833582104 CREATE)
[n:127.0.0.1:35909_solr c:awhollynewcollection_0 s:shard2 r:core_node2
x:awhollynewcollection_0_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext
I am the new leader:
http://127.0.0.1:35909/solr/awhollynewcollection_0_shard2_replica_n1/ shard2
[junit4] 2> 648834 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000
[junit4] 2> 648835 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 648835 INFO
(searcherExecutor-1127-thread-1-processing-x:awhollynewcollection_0_shard4_replica_n1
r:core_node4 e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE
n:127.0.0.1:38350_solr s:shard4 c:awhollynewcollection_0)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.c.SolrCore
[awhollynewcollection_0_shard4_replica_n1] Registered new searcher
Searcher@2b60b756[awhollynewcollection_0_shard4_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 648835 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.u.UpdateLog Could not find
max version in index or recent updates, using new clock 1573575967523209216
[junit4] 2> 648835 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000
[junit4] 2> 648835 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.u.UpdateLog Could not find
max version in index or recent updates, using new clock 1573575967523209216
[junit4] 2> 648926 INFO
(searcherExecutor-1126-thread-1-processing-x:awhollynewcollection_0_shard1_replica_n1
r:core_node1 e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE
n:127.0.0.1:35625_solr s:shard1 c:awhollynewcollection_0)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.SolrCore
[awhollynewcollection_0_shard1_replica_n1] Registered new searcher
Searcher@41f6f92f[awhollynewcollection_0_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 648944 INFO (zkCallback-419-thread-3) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 648946 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext
Enough replicas found to continue.
[junit4] 2> 648946 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext
I may be the new leader - try and sync
[junit4] 2> 648946 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas
to http://127.0.0.1:35625/solr/awhollynewcollection_0_shard1_replica_n1/
[junit4] 2> 648946 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success -
now sync replicas to me
[junit4] 2> 648946 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.SyncStrategy
http://127.0.0.1:35625/solr/awhollynewcollection_0_shard1_replica_n1/ has no
replicas
[junit4] 2> 648946 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext
Found all replicas participating in election, clear LIR
[junit4] 2> 648947 INFO
(zkCallback-408-thread-2-processing-n:127.0.0.1:35625_solr)
[n:127.0.0.1:35625_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 648947 INFO
(zkCallback-412-thread-2-processing-n:127.0.0.1:38350_solr)
[n:127.0.0.1:38350_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 648947 INFO
(zkCallback-410-thread-2-processing-n:127.0.0.1:37077_solr)
[n:127.0.0.1:37077_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 648947 INFO
(zkCallback-410-thread-1-processing-n:127.0.0.1:37077_solr)
[n:127.0.0.1:37077_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 648947 INFO
(zkCallback-412-thread-3-processing-n:127.0.0.1:38350_solr)
[n:127.0.0.1:38350_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 648954 INFO
(zkCallback-408-thread-1-processing-n:127.0.0.1:35625_solr)
[n:127.0.0.1:35625_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 648954 INFO
(zkCallback-411-thread-2-processing-n:127.0.0.1:35909_solr)
[n:127.0.0.1:35909_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 648955 INFO
(zkCallback-411-thread-1-processing-n:127.0.0.1:35909_solr)
[n:127.0.0.1:35909_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 649017 INFO
(parallelCoreAdminExecutor-1120-thread-1-processing-n:127.0.0.1:35625_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291832785992 CREATE)
[n:127.0.0.1:35625_solr c:awhollynewcollection_0 s:shard1 r:core_node1
x:awhollynewcollection_0_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext
I am the new leader:
http://127.0.0.1:35625/solr/awhollynewcollection_0_shard1_replica_n1/ shard1
[junit4] 2> 649033 INFO
(parallelCoreAdminExecutor-1122-thread-1-processing-n:127.0.0.1:37077_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834149430 CREATE)
[n:127.0.0.1:37077_solr c:awhollynewcollection_0 s:shard3 r:core_node3
x:awhollynewcollection_0_shard3_replica_n1] o.a.s.c.ShardLeaderElectionContext
I am the new leader:
http://127.0.0.1:37077/solr/awhollynewcollection_0_shard3_replica_n1/ shard3
[junit4] 2> 649033 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.c.ShardLeaderElectionContext
Enough replicas found to continue.
[junit4] 2> 649033 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.c.ShardLeaderElectionContext
I may be the new leader - try and sync
[junit4] 2> 649033 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.c.SyncStrategy Sync replicas
to http://127.0.0.1:38350/solr/awhollynewcollection_0_shard4_replica_n1/
[junit4] 2> 649033 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.c.SyncStrategy Sync Success -
now sync replicas to me
[junit4] 2> 649033 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.c.SyncStrategy
http://127.0.0.1:38350/solr/awhollynewcollection_0_shard4_replica_n1/ has no
replicas
[junit4] 2> 649033 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.c.ShardLeaderElectionContext
Found all replicas participating in election, clear LIR
[junit4] 2> 649055 INFO
(parallelCoreAdminExecutor-1117-thread-1-processing-n:127.0.0.1:38350_solr
e1d9711f-b616-4eb3-984a-812b603f98a9788291834950870 CREATE)
[n:127.0.0.1:38350_solr c:awhollynewcollection_0 s:shard4 r:core_node4
x:awhollynewcollection_0_shard4_replica_n1] o.a.s.c.ShardLeaderElectionContext
I am the new leader:
http://127.0.0.1:38350/solr/awhollynewcollection_0_shard4_replica_n1/ shard4
[junit4] 2> 649174 INFO
(zkCallback-408-thread-2-processing-n:127.0.0.1:35625_solr)
[n:127.0.0.1:35625_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 649174 INFO
(zkCallback-410-thread-2-processing-n:127.0.0.1:37077_solr)
[n:127.0.0.1:37077_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 649174 INFO
(zkCallback-408-thread-1-processing-n:127.0.0.1:35625_solr)
[n:127.0.0.1:35625_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 649175 INFO
(zkCallback-410-thread-1-processing-n:127.0.0.1:37077_solr)
[n:127.0.0.1:37077_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/awhollynewcollection_0/state.json] for collection
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 649179 INFO (parallel
[...truncated too long message...]
00,"QTime":29},"error":{"metadata":["error-class","org.apache.solr.common.SolrException","root-error-class","org.apache.solr.common.SolrException"],"msg":"ERROR:
[doc=0] unknown field 'unknown_field_0'","code":400}}
[junit4] 2>
[junit4] 2> 276550 INFO
(TEST-TestSolrJErrorHandling.testRawSocket-seed#[67D6F6A4B353A378]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testRawSocket
[junit4] 2> 276551 INFO
(SUITE-TestSolrJErrorHandling-seed#[67D6F6A4B353A378]-worker) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@3e262563{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 276551 INFO
(SUITE-TestSolrJErrorHandling-seed#[67D6F6A4B353A378]-worker) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=998558620
[junit4] 2> 276552 INFO
(SUITE-TestSolrJErrorHandling-seed#[67D6F6A4B353A378]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 276553 INFO
(SUITE-TestSolrJErrorHandling-seed#[67D6F6A4B353A378]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 276554 INFO
(SUITE-TestSolrJErrorHandling-seed#[67D6F6A4B353A378]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 276554 INFO
(SUITE-TestSolrJErrorHandling-seed#[67D6F6A4B353A378]-worker) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 276554 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@4155a14e
[junit4] 2> 276554 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-406-thread-1]:
now flush at close
[junit4] 2> 276554 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-406-thread-1]:
start flush: applyAllDeletes=true
[junit4] 2> 276554 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-406-thread-1]:
index before flush _5(7.0.0):C447/446:delGen=1 _6(7.0.0):C10/10:delGen=1
[junit4] 2> 276554 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-406-thread-1]:
startFullFlush
[junit4] 2> 276554 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-406-thread-1]:
coreCloseExecutor-406-thread-1 finishFullFlush success=true
[junit4] 2> 276554 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-406-thread-1]:
now apply all deletes for all segments buffered updates bytesUsed=0 reader pool
bytesUsed=0
[junit4] 2> 276554 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [BD][coreCloseExecutor-406-thread-1]:
waitApply: no deletes to apply
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [MS][coreCloseExecutor-406-thread-1]:
updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [MS][coreCloseExecutor-406-thread-1]:
now merge
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [MS][coreCloseExecutor-406-thread-1]:
index: _5(7.0.0):C447/446:delGen=1 _6(7.0.0):C10/10:delGen=1
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [MS][coreCloseExecutor-406-thread-1]:
no more merges pending; now return
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-406-thread-1]:
waitForMerges
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-406-thread-1]:
waitForMerges done
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-406-thread-1]:
commit: start
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-406-thread-1]:
commit: enter lock
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-406-thread-1]:
commit: now prepare
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-406-thread-1]:
prepareCommit: flush
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-406-thread-1]:
index before flush _5(7.0.0):C447/446:delGen=1 _6(7.0.0):C10/10:delGen=1
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-406-thread-1]:
startFullFlush
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-406-thread-1]:
now apply all deletes for all segments buffered updates bytesUsed=0 reader pool
bytesUsed=0
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [BD][coreCloseExecutor-406-thread-1]:
waitApply: no deletes to apply
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-406-thread-1]:
coreCloseExecutor-406-thread-1 finishFullFlush success=true
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-406-thread-1]:
startCommit(): start
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-406-thread-1]:
skip startCommit(): no changes pending
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-406-thread-1]:
commit: pendingCommit == null; skip
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-406-thread-1]:
commit: took 0.2 msec
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-406-thread-1]:
commit: done
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-406-thread-1]:
rollback
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-406-thread-1]:
all running merges have aborted
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-406-thread-1]:
rollback: done finish merges
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-406-thread-1]:
abort
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-406-thread-1]:
done abort success=true
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-406-thread-1]:
rollback: infos=_5(7.0.0):C447/446:delGen=1 _6(7.0.0):C10/10:delGen=1
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-406-thread-1]:
now checkpoint "_5(7.0.0):C447/446:delGen=1 _6(7.0.0):C10/10:delGen=1" [2
segments ; isCommit = false]
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-406-thread-1]:
0 msec to checkpoint
[junit4] 2> 276555 INFO (coreCloseExecutor-406-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1, tag=1096130894
[junit4] 2> 276558 INFO
(SUITE-TestSolrJErrorHandling-seed#[67D6F6A4B353A378]-worker) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@28d24d9e{/solr,null,UNAVAILABLE}
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.0/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.TestSolrJErrorHandling_67D6F6A4B353A378-001
[junit4] 2> NOTE: test params are: codec=Lucene70,
sim=RandomSimilarity(queryNorm=true): {}, locale=ar-OM,
timezone=Australia/Melbourne
[junit4] 2> NOTE: Linux 4.4.0-83-generic amd64/Oracle Corporation
1.8.0_131 (64-bit)/cpus=4,threads=1,free=186903768,total=463470592
[junit4] 2> NOTE: All tests run in this JVM: [ShardParamsTest,
FacetFieldTest, AddEvaluatorTest, TestPathTrie, NaturalLogEvaluatorTest,
PowerEvaluatorTest, TestClusteringResponse, SolrDocumentTest,
FieldEvaluatorTest, LBHttpSolrClientBuilderTest, TestDocumentObjectBinder,
LessThanEvaluatorTest, GetByIdTest, ClientUtilsTest, TestV1toV2ApiMapper,
StreamExpressionToExplanationTest, TestConfigSetAdminRequest,
ArcSineEvaluatorTest, SelectWithEvaluatorsTest, CloudSolrClientTest,
SolrExampleStreamingBinaryTest, TestSolrProperties, SolrExampleStreamingTest,
SolrExampleJettyTest, SolrExampleEmbeddedTest, LargeVolumeBinaryJettyTest,
TestBatchUpdate, JettyWebappTest, LargeVolumeEmbeddedTest, TermsResponseTest,
MergeIndexesEmbeddedTest, QueryResponseTest, TestJavaBinCodec, SolrQueryTest,
TestHash, ModifiableSolrParamsTest, TestXMLEscaping, TestUpdateRequestCodec,
TestSolrJErrorHandling]
[junit4] Completed [95/135 (1!)] on J1 in 64.11s, 4 tests, 1 failure <<<
FAILURES!
[...truncated 42615 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
